__step=000052
__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_sigouin_int_avl_tm_auto.debra.hybridnorec -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000052.txt
__path_data=data/data000052.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992837.608850397
binary=./brown_sigouin_int_avl_tm_auto.debra.hybridnorec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_sigouin_int_avl_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=20000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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=48
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.015s
prefilling_size=330517 prefilling_elapsed_millis=1.001s
prefilling_size=362849 prefilling_elapsed_millis=1.101s
prefilling_size=395030 prefilling_elapsed_millis=1.201s
prefilling_size=427004 prefilling_elapsed_millis=1.302s
prefilling_size=459015 prefilling_elapsed_millis=1.402s
prefilling_size=490998 prefilling_elapsed_millis=1.502s
prefilling_size=522706 prefilling_elapsed_millis=1.602s
prefilling_size=554382 prefilling_elapsed_millis=1.702s
prefilling_size=585858 prefilling_elapsed_millis=1.803s
prefilling_size=617449 prefilling_elapsed_millis=1.903s
prefilling_size=648619 prefilling_elapsed_millis=2.003s
prefilling_size=679682 prefilling_elapsed_millis=2.103s
prefilling_size=710661 prefilling_elapsed_millis=2.203s
prefilling_size=741549 prefilling_elapsed_millis=2.303s
prefilling_size=772163 prefilling_elapsed_millis=2.404s
prefilling_size=802899 prefilling_elapsed_millis=2.504s
prefilling_size=833379 prefilling_elapsed_millis=2.604s
prefilling_size=863716 prefilling_elapsed_millis=2.704s
prefilling_size=894275 prefilling_elapsed_millis=2.804s
prefilling_size=924592 prefilling_elapsed_millis=2.905s
prefilling_size=954812 prefilling_elapsed_millis=3.005s
prefilling_size=984831 prefilling_elapsed_millis=3.105s
prefilling_size=1014732 prefilling_elapsed_millis=3.205s
prefilling_size=1044490 prefilling_elapsed_millis=3.305s
prefilling_size=1074242 prefilling_elapsed_millis=3.406s
prefilling_size=1103995 prefilling_elapsed_millis=3.506s
prefilling_size=1133352 prefilling_elapsed_millis=3.606s
prefilling_size=1162748 prefilling_elapsed_millis=3.706s
prefilling_size=1192027 prefilling_elapsed_millis=3.806s
prefilling_size=1221261 prefilling_elapsed_millis=3.907s
prefilling_size=1250494 prefilling_elapsed_millis=4.007s
prefilling_size=1279534 prefilling_elapsed_millis=4.107s
prefilling_size=1308555 prefilling_elapsed_millis=4.207s
prefilling_size=1337260 prefilling_elapsed_millis=4.307s
prefilling_size=1365790 prefilling_elapsed_millis=4.408s
prefilling_size=1394566 prefilling_elapsed_millis=4.508s
prefilling_size=1423167 prefilling_elapsed_millis=4.608s
prefilling_size=1451327 prefilling_elapsed_millis=4.708s
prefilling_size=1479812 prefilling_elapsed_millis=4.808s
prefilling_size=1507984 prefilling_elapsed_millis=4.909s
prefilling_size=1536200 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8463710 keys with 96 threads...
tid=0 thread_prefill_amount=18747 percent_done_estimate=21.3 elapsed_s=6 est_remaining_s=47 / 52
tid=0 thread_prefill_amount=20830 percent_done_estimate=23.6 elapsed_s=6 est_remaining_s=45 / 51
tid=0 thread_prefill_amount=22913 percent_done_estimate=26.0 elapsed_s=7 est_remaining_s=43 / 50
tid=0 thread_prefill_amount=24996 percent_done_estimate=28.4 elapsed_s=7 est_remaining_s=42 / 49
tid=0 thread_prefill_amount=27079 percent_done_estimate=30.7 elapsed_s=8 est_remaining_s=40 / 48
tid=0 thread_prefill_amount=29162 percent_done_estimate=33.1 elapsed_s=9 est_remaining_s=39 / 48
tid=0 thread_prefill_amount=31245 percent_done_estimate=35.4 elapsed_s=9 est_remaining_s=38 / 47
tid=0 thread_prefill_amount=33328 percent_done_estimate=37.8 elapsed_s=10 est_remaining_s=36 / 46
tid=0 thread_prefill_amount=35411 percent_done_estimate=40.2 elapsed_s=10 est_remaining_s=35 / 45
tid=0 thread_prefill_amount=37494 percent_done_estimate=42.5 elapsed_s=11 est_remaining_s=33 / 44
tid=0 thread_prefill_amount=39577 percent_done_estimate=44.9 elapsed_s=12 est_remaining_s=32 / 43
tid=0 thread_prefill_amount=41660 percent_done_estimate=47.3 elapsed_s=12 est_remaining_s=30 / 43
tid=0 thread_prefill_amount=43743 percent_done_estimate=49.6 elapsed_s=13 est_remaining_s=29 / 42
tid=0 thread_prefill_amount=45826 percent_done_estimate=52.0 elapsed_s=13 est_remaining_s=28 / 41
tid=0 thread_prefill_amount=47909 percent_done_estimate=54.3 elapsed_s=14 est_remaining_s=26 / 40
tid=0 thread_prefill_amount=49992 percent_done_estimate=56.7 elapsed_s=15 est_remaining_s=25 / 39
tid=0 thread_prefill_amount=52075 percent_done_estimate=59.1 elapsed_s=15 est_remaining_s=24 / 39
tid=0 thread_prefill_amount=54158 percent_done_estimate=61.4 elapsed_s=16 est_remaining_s=22 / 38
tid=0 thread_prefill_amount=56241 percent_done_estimate=63.8 elapsed_s=16 est_remaining_s=21 / 37
tid=0 thread_prefill_amount=58324 percent_done_estimate=66.2 elapsed_s=17 est_remaining_s=19 / 36
tid=0 thread_prefill_amount=60407 percent_done_estimate=68.5 elapsed_s=18 est_remaining_s=18 / 36
tid=0 thread_prefill_amount=62490 percent_done_estimate=70.9 elapsed_s=18 est_remaining_s=17 / 35
tid=0 thread_prefill_amount=64573 percent_done_estimate=73.2 elapsed_s=19 est_remaining_s=15 / 34
tid=0 thread_prefill_amount=66656 percent_done_estimate=75.6 elapsed_s=19 est_remaining_s=14 / 33
tid=0 thread_prefill_amount=68739 percent_done_estimate=78.0 elapsed_s=20 est_remaining_s=13 / 33
tid=0 thread_prefill_amount=70822 percent_done_estimate=80.3 elapsed_s=21 est_remaining_s=11 / 32
tid=0 thread_prefill_amount=72905 percent_done_estimate=82.7 elapsed_s=21 est_remaining_s=10 / 31
tid=0 thread_prefill_amount=74988 percent_done_estimate=85.1 elapsed_s=22 est_remaining_s=9 / 30
tid=0 thread_prefill_amount=77071 percent_done_estimate=87.4 elapsed_s=22 est_remaining_s=7 / 30
tid=0 thread_prefill_amount=79154 percent_done_estimate=89.8 elapsed_s=23 est_remaining_s=6 / 29
tid=0 thread_prefill_amount=81237 percent_done_estimate=92.1 elapsed_s=23 est_remaining_s=5 / 28
tid=0 thread_prefill_amount=83320 percent_done_estimate=94.5 elapsed_s=24 est_remaining_s=3 / 27
tid=0 thread_prefill_amount=85403 percent_done_estimate=96.9 elapsed_s=25 est_remaining_s=2 / 26
tid=0 thread_prefill_amount=87486 percent_done_estimate=99.2 elapsed_s=25 est_remaining_s=0 / 26
tid=0 thread_prefill_amount=89569 percent_done_estimate=101.6 elapsed_s=26 est_remaining_s=-1 / 25
tid=0 thread_prefill_amount=91652 percent_done_estimate=104.0 elapsed_s=26 est_remaining_s=-2 / 24
tid=0 thread_prefill_amount=93735 percent_done_estimate=106.3 elapsed_s=27 est_remaining_s=-4 / 23
tid=0 thread_prefill_amount=95818 percent_done_estimate=108.7 elapsed_s=28 est_remaining_s=-5 / 23
tid=0 thread_prefill_amount=97901 percent_done_estimate=111.0 elapsed_s=28 est_remaining_s=-6 / 22
tid=0 thread_prefill_amount=99984 percent_done_estimate=113.4 elapsed_s=29 est_remaining_s=-8 / 21
tid=0 thread_prefill_amount=102067 percent_done_estimate=115.8 elapsed_s=29 est_remaining_s=-9 / 20
tid=0 thread_prefill_amount=104150 percent_done_estimate=118.1 elapsed_s=30 est_remaining_s=-10 / 20
timing_elapsed 25.6058s
prefilling_size=10000000 prefilling_elapsed_millis=25.667s
finished prefilling to size 10000000 for expected size 10000000 keysum=99974545240541, performing 15603456 updates; total_prefilling_elapsed_ms=25667 ms)
pref_size=10000000
pref_millis=25667
prefill_elapsed_ms=25667
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992868.596160883
REALTIME_END_PERF_FORMAT=6992878.596283388

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

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

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

tree_stats_computeWalltime=0.026s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 262041 519884 970050 1453993 1402959 706094 151201 10309 155 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 92 3806 60326 399902 1171126 1543658 876290 192592 12873 182
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523690 1030376 1853895 2574085 2249752 1027491 202901 13028 182
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523690 1030376 1853895 2574085 2249752 1027491 202901 13028 182
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99993 1.99815 1.9748 1.85779 1.60418 1.32897 1.14286 1.05032 1.0134 1.00207 1

tree_stats_height=28
tree_stats_numInternals=5738827
tree_stats_numLeaves=4260849
tree_stats_numNodes=9999676
tree_stats_numKeys=9999676

tree_stats_avgDegreeInternal=1.74246
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.4261
tree_stats_avgKeyDepth=21.6859


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
    [2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=96383
first_thread_announced_epoch_by_thread=2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414 2414
sum_num_inserts_by_thread=34264 33994 34432 33623 33412 32867 34433 34329 34852 33690 33943 33248 34759 34071 33884 33696 33545 33718 33884 34397 33779 33380 33402 33263 34244 34963 33912 32973 33178 33041 34481 34335 34915 33917 33592 33370 34577 34344 34239 33693 33505 34020 34176 33996 34423 33935 33251 33328 35454 36380 36080 35743 35629 35216 36182 36098 36725 35763 35785 35898 36679 36437 35754 35431 35595 34954 36008 35743 36282 35455 35774 35442 36051 36286 36108 35874 35564 35517 36006 36333 36721 35946 36180 35872 36389 36617 35985 35173 35366 34736 36220 36013 35870 35711 35482 35100
sum_num_inserts_total=3348900
sum_num_deletes_by_thread=34143 33985 34039 33212 33339 33091 34658 34396 34716 34056 33437 33154 34665 33985 33776 33078 33536 33829 33838 34555 33948 33553 33310 33269 34263 34460 34070 32681 33492 33250 34104 34754 34696 33417 33631 33121 34477 34339 34320 33342 33903 33858 34217 34387 34002 33784 33458 33513 35419 36077 36273 35813 35462 35601 36183 36219 36564 36327 36125 35444 36971 36302 35992 35729 35196 35245 36120 35618 36361 35497 35903 35631 35969 35788 36298 35648 35370 35418 36278 36169 36652 35610 36280 35890 36357 36402 35943 35387 35687 35033 35934 35963 36189 36022 35233 35495
sum_num_deletes_total=3348194
sum_num_searches_by_thread=614378 612003 613151 599923 598572 595021 619697 615903 623415 607732 606692 599401 625713 613373 605058 597242 599436 605914 609204 618308 607147 604009 603312 600485 616631 620429 610807 592239 600623 601539 616144 618415 624357 603087 603915 596545 622776 613661 613526 604231 609941 610435 611664 612818 612595 610958 601718 603304 639860 653392 646793 644085 642105 637644 651188 651361 661151 647445 646130 644492 659053 657240 642853 638087 635727 632088 649091 647984 651554 641476 645960 644030 647556 649875 647844 647652 636115 635925 646983 655207 652192 642092 651434 649160 651599 657950 648877 640052 636030 631894 649733 643652 644881 641440 638086 638461
sum_num_searches_total=60222926
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=682785 679982 681622 666758 665323 660979 688788 684628 692983 675478 674072 665803 695137 681429 672718 664016 666517 673461 676926 687260 674874 670942 670024 667017 685138 689852 678789 657893 667293 667830 684729 687504 693968 670421 671138 663036 691830 682344 682085 671266 677349 678313 680057 681201 681020 678677 668427 670145 710733 725849 719146 715641 713196 708461 723553 723678 734440 719535 718040 715834 732703 729979 714599 709247 706518 702287 721219 719345 724197 712428 717637 715103 719576 721949 720250 719174 707049 706860 719267 727709 725565 713648 723894 720922 724345 730969 720805 710612 707083 701663 721887 715628 716940 713173 708801 709056
average_num_operations_total=697083
stdev_num_operations_total=2280
sum_num_operations_total=66920020
min_num_operations_total=657893
max_num_operations_total=734440
first_time_thread_terminate_by_thread=10000197 10000194 10000229 10000189 10000122 10000215 10000209 10000213 10000246 10000230 10000256 10000122 10000217 10000205 10000227 10000124 10000147 10000220 10000124 10000225 10000140 10000210 10000242 10000130 10000185 10000124 10000245 10000124 10000231 10000244 10000228 10000158 10000155 10000135 10000243 10000232 10000247 10000204 10000199 10000124 10000207 10000192 10000218 10000224 10000221 10000144 10000242 10000181 10000151 10000188 10000237 10000250 10000187 10000123 10000248 10000124 10000163 10000203 10000124 10000152 10000123 10000171 10000235 10000124 10000175 10000122 10000126 10000222 10000173 10000124 10000161 10000253 10000178 10000165 10000223 10000169 10000241 10000249 10000248 10000240 10000196 10000167 10000238 10000124 10000183 10000127 10000234 10000124 10000176 10000200 10000125 10000202 10000211 10000122 10000182 10000239
min_time_thread_terminate_total=10000122
max_time_thread_terminate_total=10000256
first_time_thread_start_by_thread=16 5 15 5 5 5 14 5 5 15 5 15 15 5 15 15 5 5 15 5 5 5 5 5 4 5 15 5 5 5 14 5 5 15 5 15 15 5 15 15 5 5 15 5 5 5 5 5 6 6 6 6 5 5 5 5 5 6 6 5 5 5 6 5 6 5 5 5 5 5 5 6 6 6 6 6 5 5 5 5 5 6 6 5 5 5 6 5 6 5 5 5 5 5 5 6
min_time_thread_start_total=4
max_time_thread_start_total=16
sum_duration_all_ops_total=

threads_final_keysum=99959421632813
threads_final_size=9999676
final_keysum=99959421632813
final_size=9999676
validate_result=success
Validation OK.
Structural validation OK.

total_find=60222926
total_rq=0
total_inserts=3348900
total_deletes=3348194
total_updates=6697094
total_queries=60222926
total_ops=66920020
find_throughput=6022292
rq_throughput=0
update_throughput=669709
query_throughput=6022292
total_throughput=6692002


total find                    : 60222926
total rq                      : 0
total inserts                 : 3348900
total deletes                 : 3348194
total updates                 : 6697094
total queries                 : 60222926
total ops                     : 66920020
find throughput               : 6022292
rq throughput                 : 0
update throughput             : 669709
query throughput              : 6022292
total throughput              : 6692002

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=32.9294
PAPI_L3_TCM=10.2435
PAPI_TOT_CYC=39786
PAPI_TOT_INS=6185.19
garbage=297938996024034
total_execution_walltime=41.109s
REALTIME_MAIN_END_PERF_FORMAT=6992878.734360324
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 71457393
[slow htm]
[fallback]
total fallback commit         : 11066084
seconds global lock is held   : 2.19656
[time_cmd_output] time_elapsed_sec=41.31, faults_major=0, faults_minor=222285, mem_maxresident_kb=886832, user_cputime=3898.17, sys_cputime=9.23, percent_cpu=9457%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2