__step=000466
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 numactl -i all /usr/bin/time -f "[time_cmd_output] time_elapsed_sec=%e, faults_major=%F, faults_minor=%R, mem_maxresident_kb=%M, user_cputime=%U, sys_cputime=%S, percent_cpu=%P" ./brown_int_bst_tm_auto.debra.hytm1 -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000466.txt
__path_data=data/data000466.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=7000416.146240595
binary=./brown_int_bst_tm_auto.debra.hytm1
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_tm_auto
FIND_FUNC=FIND_FUNC
INSERT_FUNC=insertIfAbsent
ERASE_FUNC=ERASE_FUNC
RQ_FUNC=RQ_FUNC
RECLAIM=reclaimer_debra
ALLOC=allocator_new
POOL=pool_none
MAX_THREADS_POW2=512
CPU_FREQ_GHZ=2.1
MILLIS_TO_RUN=10000
INS_FRAC=5
DEL_FRAC=5
RQ=0
RQSIZE=0
MAXKEY=200000
PREFILL_THREADS=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=5.0 5.0
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=32
ACTUAL_THREAD_BINDINGS=0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=100182 prefilling_elapsed_millis=1.001s
finished prefilling to size 100075 for expected size 100000 keysum=9997469504, performing 52517764 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100075
pref_millis=1102
prefill_elapsed_ms=1119
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=2467

main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=7000417.417590863
REALTIME_END_PERF_FORMAT=7000427.417754310

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (192 threads)...
partitioned into 1164 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.038s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 62 114 206 375 640 1038 1599 2227 2924 3716 4432 5017 5286 5502 5507 5337 4827 4163 3426 2773 2128 1539 1093 654 372 211 85 33 12 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 2 6 10 20 53 126 262 508 821 1129 1626 2048 2519 2696 2882 2973 3087 2916 2606 2132 1783 1430 1027 839 493 272 171 67 25 11 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 120 216 395 693 1164 1861 2735 3745 4845 6058 7065 7805 8198 8389 8310 7914 7079 6032 4905 3911 2969 2120 1493 865 483 256 100 37 12 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 120 216 395 693 1164 1861 2735 3745 4845 6058 7065 7805 8198 8389 8310 7914 7079 6032 4905 3911 2969 2120 1493 865 483 256 100 37 12 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.90625 1.85 1.875 1.80506 1.75613 1.70704 1.61042 1.55503 1.51295 1.48338 1.43463 1.39462 1.37309 1.35216 1.33413 1.31011 1.28456 1.26402 1.24519 1.23201 1.21503 1.19569 1.18868 1.14133 1.12832 1.09317 1.05859 1.04 1 1 1

tree_stats_height=37
tree_stats_numInternals=65362
tree_stats_numLeaves=34541
tree_stats_numNodes=99903
tree_stats_numKeys=99903

tree_stats_avgDegreeInternal=1.52846
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34575
tree_stats_avgKeyDepth=20.0997

4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=32540


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:85 2:2193 4:62280 8:366328 16:49006 32:2
    [2^00, 2^01]: 85
    (2^01, 2^02]: 2193
    (2^02, 2^03]: 62280
    (2^03, 2^04]: 366328
    (2^04, 2^05]: 49006
    (2^05, 2^06]: 2
sum_limbo_reclamation_event_size_total=5354327
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9996 9997 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9997 9998 9996 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=479894
sum_limbo_reclamation_event_count_total=1443504
first_thread_announced_epoch_by_thread=65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080 65080
sum_num_inserts_by_thread=690789 668917 702668 560265 655131 682956 671534 693356 677459 706114 685860 692331 695265 689487 685945 700442 694473 631261 601141 697224 687268 689739 680344 634247 689257 687156 699909 539536 613569 636509 643302 697938 681094 694195 639541 691290 694451 693193 656224 637544 652901 687072 684896 696765 700737 693863 690414 639668
sum_num_inserts_total=32215240
sum_num_deletes_by_thread=691006 667710 702712 561110 659566 681075 671911 692491 676744 706323 686752 693660 695203 689034 687031 698481 694008 629913 602012 696153 688382 689703 682031 634449 689031 688353 698453 541486 613808 635254 644675 698547 683035 692441 639495 691352 695205 691693 656998 637231 653690 688060 683599 699063 701534 694805 689288 641997
sum_num_deletes_total=32226553
sum_num_searches_by_thread=12445670 12010352 12646212 10084930 11816328 12278883 12098376 12476538 12202385 12694365 12351031 12449698 12500550 12422153 12331999 12602406 12498775 11349091 10797326 12545055 12385035 12423510 12257253 11421780 12369004 12356975 12598178 9726169 11040327 11451950 11597713 12553334 12282179 12467008 11530146 12458255 12495484 12484197 11818430 11468410 11752939 12367258 12320006 12550058 12618486 12493828 12420128 11534869
sum_num_searches_total=579845032
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=13827465 13346979 14051592 11206305 13131025 13642914 13441821 13862385 13556588 14106802 13723643 13835689 13891018 13800674 13704975 14001329 13887256 12610265 12000479 13938432 13760685 13802952 13619628 12690476 13747292 13732484 13996540 10807191 12267704 12723713 12885690 13949819 13646308 13853644 12809182 13840897 13885140 13869083 13131652 12743185 13059530 13742390 13688501 13945886 14020757 13882496 13799830 12816534
average_num_operations_total=13422642
stdev_num_operations_total=105294
sum_num_operations_total=644286825
min_num_operations_total=10807191
max_num_operations_total=14106802
first_time_thread_terminate_by_thread=10000162 10000163 10000162 10000163 10000163 10000163 10000162 10000162 10000162 10000162 10000163 10000163 10000163 10000162 10000162 10000162 10000163 10000162 10000162 10000163 10000162 10000162 10000162 10000162 10000162 10000163 10000162 10000163 10000163 10000163 10000162 10000162 10000162 10000162 10000163 10000163 10000163 10000162 10000162 10000162 10000163 10000162 10000162 10000163 10000162 10000162 10000162 10000162
min_time_thread_terminate_total=10000162
max_time_thread_terminate_total=10000163
first_time_thread_start_by_thread=2 2 3 3 3 3 3 3 3 3 2 3 3 3 2 3 3 2 3 3 3 3 3 3 2 2 3 3 3 3 3 3 3 3 2 3 3 3 2 3 3 2 3 3 3 3 3 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=9972595767
threads_final_size=99903
final_keysum=9972595767
final_size=99903
validate_result=success
Validation OK.
Structural validation OK.

total_find=579845032
total_rq=0
total_inserts=32215240
total_deletes=32226553
total_updates=64441793
total_queries=579845032
total_ops=644286825
find_throughput=57984503
rq_throughput=0
update_throughput=6444179
query_throughput=57984503
total_throughput=64428682


total find                    : 579845032
total rq                      : 0
total inserts                 : 32215240
total deletes                 : 32226553
total updates                 : 64441793
total queries                 : 579845032
total ops                     : 644286825
find throughput               : 57984503
rq throughput                 : 0
update throughput             : 6444179
query throughput              : 57984503
total throughput              : 64428682

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=3.58096
PAPI_L3_TCM=0.000176819
PAPI_TOT_CYC=1975.42
PAPI_TOT_INS=1130.49
garbage=28989752981137
total_execution_walltime=11.407s
REALTIME_MAIN_END_PERF_FORMAT=7000427.554124034
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 696709515
[slow htm]
[fallback]
total fallback commit         : 95075
seconds global lock is held   : 0.108456
[time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=78335, mem_maxresident_kb=310008, user_cputime=561.77, sys_cputime=3.67, percent_cpu=4902%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2