__step=000051
__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=data000051.txt
__path_data=data/data000051.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992799.51856075
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.014s
prefilling_size=327372 prefilling_elapsed_millis=1.001s
prefilling_size=359612 prefilling_elapsed_millis=1.101s
prefilling_size=391689 prefilling_elapsed_millis=1.201s
prefilling_size=423661 prefilling_elapsed_millis=1.301s
prefilling_size=455381 prefilling_elapsed_millis=1.402s
prefilling_size=487079 prefilling_elapsed_millis=1.502s
prefilling_size=518620 prefilling_elapsed_millis=1.602s
prefilling_size=550151 prefilling_elapsed_millis=1.702s
prefilling_size=581395 prefilling_elapsed_millis=1.802s
prefilling_size=612449 prefilling_elapsed_millis=1.903s
prefilling_size=643550 prefilling_elapsed_millis=2.003s
prefilling_size=674593 prefilling_elapsed_millis=2.103s
prefilling_size=705379 prefilling_elapsed_millis=2.203s
prefilling_size=736010 prefilling_elapsed_millis=2.303s
prefilling_size=766520 prefilling_elapsed_millis=2.404s
prefilling_size=796968 prefilling_elapsed_millis=2.504s
prefilling_size=827329 prefilling_elapsed_millis=2.604s
prefilling_size=857667 prefilling_elapsed_millis=2.704s
prefilling_size=887831 prefilling_elapsed_millis=2.804s
prefilling_size=918025 prefilling_elapsed_millis=2.904s
prefilling_size=947869 prefilling_elapsed_millis=3.005s
prefilling_size=977575 prefilling_elapsed_millis=3.105s
prefilling_size=1007251 prefilling_elapsed_millis=3.205s
prefilling_size=1036865 prefilling_elapsed_millis=3.305s
prefilling_size=1066302 prefilling_elapsed_millis=3.405s
prefilling_size=1095815 prefilling_elapsed_millis=3.506s
prefilling_size=1125277 prefilling_elapsed_millis=3.606s
prefilling_size=1154389 prefilling_elapsed_millis=3.706s
prefilling_size=1183332 prefilling_elapsed_millis=3.806s
prefilling_size=1212368 prefilling_elapsed_millis=3.906s
prefilling_size=1241157 prefilling_elapsed_millis=4.007s
prefilling_size=1269945 prefilling_elapsed_millis=4.107s
prefilling_size=1298664 prefilling_elapsed_millis=4.207s
prefilling_size=1327339 prefilling_elapsed_millis=4.307s
prefilling_size=1355770 prefilling_elapsed_millis=4.407s
prefilling_size=1384146 prefilling_elapsed_millis=4.508s
prefilling_size=1412280 prefilling_elapsed_millis=4.608s
prefilling_size=1440547 prefilling_elapsed_millis=4.708s
prefilling_size=1468813 prefilling_elapsed_millis=4.808s
prefilling_size=1496724 prefilling_elapsed_millis=4.908s
prefilling_size=1524467 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8475433 keys with 96 threads...
tid=0 thread_prefill_amount=20830 percent_done_estimate=23.6 elapsed_s=6 est_remaining_s=40 / 46
tid=0 thread_prefill_amount=22913 percent_done_estimate=26.0 elapsed_s=6 est_remaining_s=39 / 45
tid=0 thread_prefill_amount=24996 percent_done_estimate=28.3 elapsed_s=7 est_remaining_s=37 / 44
tid=0 thread_prefill_amount=27079 percent_done_estimate=30.7 elapsed_s=7 est_remaining_s=36 / 43
tid=0 thread_prefill_amount=29162 percent_done_estimate=33.0 elapsed_s=8 est_remaining_s=35 / 42
tid=0 thread_prefill_amount=31245 percent_done_estimate=35.4 elapsed_s=8 est_remaining_s=33 / 41
tid=0 thread_prefill_amount=33328 percent_done_estimate=37.8 elapsed_s=9 est_remaining_s=32 / 41
tid=0 thread_prefill_amount=35411 percent_done_estimate=40.1 elapsed_s=9 est_remaining_s=31 / 40
tid=0 thread_prefill_amount=37494 percent_done_estimate=42.5 elapsed_s=10 est_remaining_s=29 / 39
tid=0 thread_prefill_amount=39577 percent_done_estimate=44.8 elapsed_s=10 est_remaining_s=28 / 38
tid=0 thread_prefill_amount=41660 percent_done_estimate=47.2 elapsed_s=11 est_remaining_s=27 / 37
tid=0 thread_prefill_amount=43743 percent_done_estimate=49.5 elapsed_s=11 est_remaining_s=26 / 37
tid=0 thread_prefill_amount=45826 percent_done_estimate=51.9 elapsed_s=12 est_remaining_s=24 / 36
tid=0 thread_prefill_amount=47909 percent_done_estimate=54.3 elapsed_s=12 est_remaining_s=23 / 35
tid=0 thread_prefill_amount=49992 percent_done_estimate=56.6 elapsed_s=13 est_remaining_s=22 / 35
tid=0 thread_prefill_amount=52075 percent_done_estimate=59.0 elapsed_s=13 est_remaining_s=21 / 34
tid=0 thread_prefill_amount=54158 percent_done_estimate=61.3 elapsed_s=14 est_remaining_s=19 / 33
tid=0 thread_prefill_amount=56241 percent_done_estimate=63.7 elapsed_s=14 est_remaining_s=18 / 33
tid=0 thread_prefill_amount=58324 percent_done_estimate=66.1 elapsed_s=15 est_remaining_s=17 / 32
tid=0 thread_prefill_amount=60407 percent_done_estimate=68.4 elapsed_s=15 est_remaining_s=16 / 31
tid=0 thread_prefill_amount=62490 percent_done_estimate=70.8 elapsed_s=16 est_remaining_s=15 / 30
tid=0 thread_prefill_amount=64573 percent_done_estimate=73.1 elapsed_s=16 est_remaining_s=13 / 30
tid=0 thread_prefill_amount=66656 percent_done_estimate=75.5 elapsed_s=17 est_remaining_s=12 / 29
tid=0 thread_prefill_amount=68739 percent_done_estimate=77.9 elapsed_s=17 est_remaining_s=11 / 28
tid=0 thread_prefill_amount=70822 percent_done_estimate=80.2 elapsed_s=18 est_remaining_s=10 / 28
tid=0 thread_prefill_amount=72905 percent_done_estimate=82.6 elapsed_s=18 est_remaining_s=9 / 27
tid=0 thread_prefill_amount=74988 percent_done_estimate=84.9 elapsed_s=19 est_remaining_s=8 / 26
tid=0 thread_prefill_amount=77071 percent_done_estimate=87.3 elapsed_s=19 est_remaining_s=6 / 26
tid=0 thread_prefill_amount=79154 percent_done_estimate=89.7 elapsed_s=20 est_remaining_s=5 / 25
tid=0 thread_prefill_amount=81237 percent_done_estimate=92.0 elapsed_s=20 est_remaining_s=4 / 24
tid=0 thread_prefill_amount=83320 percent_done_estimate=94.4 elapsed_s=21 est_remaining_s=3 / 24
tid=0 thread_prefill_amount=85403 percent_done_estimate=96.7 elapsed_s=21 est_remaining_s=2 / 23
tid=0 thread_prefill_amount=87486 percent_done_estimate=99.1 elapsed_s=22 est_remaining_s=0 / 22
tid=0 thread_prefill_amount=89569 percent_done_estimate=101.5 elapsed_s=23 est_remaining_s=-1 / 22
tid=0 thread_prefill_amount=91652 percent_done_estimate=103.8 elapsed_s=23 est_remaining_s=-2 / 21
tid=0 thread_prefill_amount=93735 percent_done_estimate=106.2 elapsed_s=24 est_remaining_s=-3 / 20
tid=0 thread_prefill_amount=95818 percent_done_estimate=108.5 elapsed_s=24 est_remaining_s=-4 / 20
tid=0 thread_prefill_amount=97901 percent_done_estimate=110.9 elapsed_s=25 est_remaining_s=-5 / 19
tid=0 thread_prefill_amount=99984 percent_done_estimate=113.3 elapsed_s=25 est_remaining_s=-7 / 18
tid=0 thread_prefill_amount=102067 percent_done_estimate=115.6 elapsed_s=25 est_remaining_s=-8 / 18
tid=0 thread_prefill_amount=104150 percent_done_estimate=118.0 elapsed_s=26 est_remaining_s=-9 / 17
tid=0 thread_prefill_amount=106233 percent_done_estimate=120.3 elapsed_s=26 est_remaining_s=-10 / 16
timing_elapsed 22.8069s
prefilling_size=10000000 prefilling_elapsed_millis=22.861s
finished prefilling to size 10000000 for expected size 10000000 keysum=100010382926684, performing 15579898 updates; total_prefilling_elapsed_ms=22861 ms)
pref_size=10000000
pref_millis=22861
prefill_elapsed_ms=22861
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992827.220166173
REALTIME_END_PERF_FORMAT=6992837.220248825

###############################################################################
################################## 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.028s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262080 520782 978426 1475687 1409074 686378 136875 7885 32 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 63 3066 54913 397388 1200858 1563992 856677 174690 9887 37
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 523848 1033339 1873075 2609932 2250370 993552 182575 9919 37
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 523848 1033339 1873075 2609932 2250370 993552 182575 9919 37
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99999 1.99857 1.97845 1.86578 1.60555 1.32234 1.1365 1.046 1.01114 1.0005 1

tree_stats_height=28
tree_stats_numInternals=5739362
tree_stats_numLeaves=4261571
tree_stats_numNodes=10000933
tree_stats_numKeys=10000933

tree_stats_avgDegreeInternal=1.74252
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42612
tree_stats_avgKeyDepth=21.6693


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=111358
first_thread_announced_epoch_by_thread=2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878 2878
sum_num_inserts_by_thread=40770 40903 41673 41769 41558 44002 41950 42247 41370 41752 41580 44425 41887 41170 41368 41549 41548 44006 41353 41059 41763 41993 41821 43674 40760 41324 41719 41534 41889 43500 41263 42037 42191 41652 42234 44214 41232 41225 41725 41595 41918 44114 41616 41142 41259 41976 41553 43752 26113 26457 26553 26173 25546 26086 26360 26230 26931 26309 26128 27559 26700 26499 26094 26455 25755 27185 25906 26255 26380 26548 26445 27707 25813 26226 26415 25963 26233 26037 26373 26256 26700 26591 26316 27809 26880 26787 26411 26362 26136 27441 25822 26169 26518 26153 26503 27598
sum_num_inserts_total=3284500
sum_num_deletes_by_thread=40758 40767 41923 41646 41458 43771 42061 41943 41870 42005 41636 44246 41812 41207 41292 41392 41613 44053 41078 41164 41807 42128 41994 43514 40967 41565 41418 41687 41560 43728 41922 41927 41657 42051 41784 44606 41202 41525 41432 41761 42028 44048 41566 41562 41634 42574 41893 43913 26302 26421 26178 25981 25767 26091 26199 26300 26623 26591 26204 27853 26628 26670 26372 26324 25441 27268 26178 26338 26601 26361 26122 27360 25852 26136 26271 26002 25820 26371 26496 26141 26968 26636 26033 27982 26877 26677 26418 26166 26193 26938 25920 26070 26198 26057 26495 27326
sum_num_deletes_total=3285364
sum_num_searches_by_thread=733918 736693 750943 751457 748085 786995 757701 756749 751731 756466 753461 799839 751726 745929 746209 749123 746028 791227 744596 745905 751867 756393 751625 787109 736265 743042 748270 747892 750533 782945 751136 752685 755270 750910 756992 800427 740678 748871 753412 747469 756079 797074 752487 745944 749536 757928 746742 784331 474898 478628 478268 470640 459717 471094 472460 471328 482710 476834 468990 502191 481192 475596 474392 477620 465888 488032 468202 473841 476184 478208 473872 490709 465684 474530 470520 467537 469699 467783 478821 473090 484696 481831 470782 502557 479533 483280 476388 469972 469500 492667 466336 473429 474711 472674 473550 492419
sum_num_searches_total=59172176
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=815446 818363 834539 834872 831101 874768 841712 840939 834971 840223 836677 888510 835425 828306 828869 832064 829189 879286 827027 828128 835437 840514 835440 874297 817992 825931 831407 831113 833982 870173 834321 836649 839118 834613 841010 889247 823112 831621 836569 830825 840025 885236 835669 828648 832429 842478 830188 871996 527313 531506 530999 522794 511030 523271 525019 523858 536264 529734 521322 557603 534520 528765 526858 530399 517084 542485 520286 526434 529165 531117 526439 545776 517349 526892 523206 519502 521752 520191 531690 525487 538364 535058 523131 558348 533290 536744 529217 522500 521829 547046 518078 525668 527427 524884 526548 547343
average_num_operations_total=684812
stdev_num_operations_total=16121
sum_num_operations_total=65742040
min_num_operations_total=511030
max_num_operations_total=889247
first_time_thread_terminate_by_thread=10000203 10000098 10000083 10000129 10000083 10000082 10000181 10000436 10000433 10000082 10000182 10000429 10000130 10000081 10000431 10000434 10000084 10000206 10000441 10000180 10000136 10000126 10000427 10000106 10000437 10000160 10000435 10000124 10000157 10000087 10000140 10000138 10000433 10000086 10000183 10000208 10000155 10000146 10000443 10000082 10000083 10000082 10000205 10000082 10000131 10000082 10000428 10000082 10000192 10000175 10000212 10000119 10000144 10000084 10000084 10000439 10000094 10000083 10000189 10000172 10000177 10000085 10000113 10000135 10000116 10000108 10000210 10000154 10000085 10000091 10000426 10000171 10000212 10000431 10000201 10000195 10000193 10000196 10000438 10000197 10000150 10000103 10000440 10000164 10000119 10000209 10000199 10000190 10000178 10000121 10000211 10000152 10000090 10000101 10000187 10000167
min_time_thread_terminate_total=10000081
max_time_thread_terminate_total=10000443
first_time_thread_start_by_thread=10 4 4 4 5 5 4 5 5 5 4 5 5 5 4 4 5 5 4 4 5 4 4 5 4 4 4 4 5 5 4 5 5 5 4 5 5 5 4 4 5 5 4 4 5 4 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100024974449868
threads_final_size=10000933
final_keysum=100024974449868
final_size=10000933
validate_result=success
Validation OK.
Structural validation OK.

total_find=59172176
total_rq=0
total_inserts=3284500
total_deletes=3285364
total_updates=6569864
total_queries=59172176
total_ops=65742040
find_throughput=5917217
rq_throughput=0
update_throughput=656986
query_throughput=5917217
total_throughput=6574204


total find                    : 59172176
total rq                      : 0
total inserts                 : 3284500
total deletes                 : 3285364
total updates                 : 6569864
total queries                 : 59172176
total ops                     : 65742040
find throughput               : 5917217
rq throughput                 : 0
update throughput             : 656986
query throughput              : 5917217
total throughput              : 6574204

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=32.6198
PAPI_L3_TCM=10.1254
PAPI_TOT_CYC=40439.6
PAPI_TOT_INS=5983.32
garbage=284397093128868
total_execution_walltime=38.308s
REALTIME_MAIN_END_PERF_FORMAT=6992837.360878314
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 71508856
[slow htm]
[fallback]
total fallback commit         : 9813083
seconds global lock is held   : 2.02538
[time_cmd_output] time_elapsed_sec=38.49, faults_major=0, faults_minor=221828, mem_maxresident_kb=884992, user_cputime=3534.04, sys_cputime=9.21, percent_cpu=9204%
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=1