__step=000010
__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.hybridnorec -nwork 48 -nprefill 48 -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=data000010.txt
__path_data=data/data000010.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991416.640445284
binary=./brown_int_bst_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_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=20000000
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
Hybrid noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.009s
prefilling_size=776509 prefilling_elapsed_millis=1.001s
prefilling_size=850743 prefilling_elapsed_millis=1.101s
prefilling_size=924265 prefilling_elapsed_millis=1.201s
prefilling_size=997363 prefilling_elapsed_millis=1.301s
prefilling_size=1069543 prefilling_elapsed_millis=1.401s
prefilling_size=1141206 prefilling_elapsed_millis=1.502s
prefilling_size=1212291 prefilling_elapsed_millis=1.602s
prefilling_size=1282701 prefilling_elapsed_millis=1.702s
prefilling_size=1352785 prefilling_elapsed_millis=1.802s
prefilling_size=1422402 prefilling_elapsed_millis=1.902s
prefilling_size=1491177 prefilling_elapsed_millis=2.003s
prefilling_size=1560122 prefilling_elapsed_millis=2.103s
prefilling_size=1628544 prefilling_elapsed_millis=2.203s
prefilling_size=1696333 prefilling_elapsed_millis=2.303s
prefilling_size=1763134 prefilling_elapsed_millis=2.403s
prefilling_size=1829196 prefilling_elapsed_millis=2.503s
prefilling_size=1895546 prefilling_elapsed_millis=2.604s
prefilling_size=1961242 prefilling_elapsed_millis=2.704s
prefilling_size=2026463 prefilling_elapsed_millis=2.804s
prefilling_size=2091025 prefilling_elapsed_millis=2.904s
prefilling_size=2155481 prefilling_elapsed_millis=3.004s
prefilling_size=2219477 prefilling_elapsed_millis=3.105s
prefilling_size=2282795 prefilling_elapsed_millis=3.205s
prefilling_size=2345546 prefilling_elapsed_millis=3.305s
prefilling_size=2407946 prefilling_elapsed_millis=3.405s
prefilling_size=2469797 prefilling_elapsed_millis=3.505s
prefilling_size=2531208 prefilling_elapsed_millis=3.606s
prefilling_size=2591699 prefilling_elapsed_millis=3.706s
prefilling_size=2651957 prefilling_elapsed_millis=3.806s
prefilling_size=2711361 prefilling_elapsed_millis=3.906s
prefilling_size=2770789 prefilling_elapsed_millis=4.006s
prefilling_size=2830178 prefilling_elapsed_millis=4.107s
prefilling_size=2888569 prefilling_elapsed_millis=4.207s
prefilling_size=2946279 prefilling_elapsed_millis=4.307s
prefilling_size=3003930 prefilling_elapsed_millis=4.407s
prefilling_size=3060925 prefilling_elapsed_millis=4.507s
prefilling_size=3117291 prefilling_elapsed_millis=4.607s
prefilling_size=3173608 prefilling_elapsed_millis=4.708s
prefilling_size=3229346 prefilling_elapsed_millis=4.808s
prefilling_size=3285039 prefilling_elapsed_millis=4.908s
prefilling_size=3340151 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6659780 keys with 48 threads...
tid=0 thread_prefill_amount=70822 percent_done_estimate=51.0 elapsed_s=5 est_remaining_s=12 / 17
tid=0 thread_prefill_amount=74988 percent_done_estimate=54.0 elapsed_s=6 est_remaining_s=11 / 16
tid=0 thread_prefill_amount=79154 percent_done_estimate=57.0 elapsed_s=6 est_remaining_s=10 / 16
tid=0 thread_prefill_amount=83320 percent_done_estimate=60.1 elapsed_s=6 est_remaining_s=9 / 15
tid=0 thread_prefill_amount=87486 percent_done_estimate=63.1 elapsed_s=6 est_remaining_s=8 / 15
tid=0 thread_prefill_amount=91652 percent_done_estimate=66.1 elapsed_s=7 est_remaining_s=8 / 14
tid=0 thread_prefill_amount=95818 percent_done_estimate=69.1 elapsed_s=7 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=99984 percent_done_estimate=72.1 elapsed_s=7 est_remaining_s=6 / 13
tid=0 thread_prefill_amount=104150 percent_done_estimate=75.1 elapsed_s=7 est_remaining_s=6 / 13
tid=0 thread_prefill_amount=108316 percent_done_estimate=78.1 elapsed_s=8 est_remaining_s=5 / 13
tid=0 thread_prefill_amount=112482 percent_done_estimate=81.1 elapsed_s=8 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=116648 percent_done_estimate=84.1 elapsed_s=8 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=120814 percent_done_estimate=87.1 elapsed_s=9 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=124980 percent_done_estimate=90.1 elapsed_s=9 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=129146 percent_done_estimate=93.1 elapsed_s=9 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=133312 percent_done_estimate=96.1 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=137478 percent_done_estimate=99.1 elapsed_s=10 est_remaining_s=0 / 10
tid=0 thread_prefill_amount=141644 percent_done_estimate=102.1 elapsed_s=10 est_remaining_s=-0 / 9
tid=0 thread_prefill_amount=145810 percent_done_estimate=105.1 elapsed_s=10 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=149976 percent_done_estimate=108.1 elapsed_s=10 est_remaining_s=-2 / 9
tid=0 thread_prefill_amount=154142 percent_done_estimate=111.1 elapsed_s=11 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=158308 percent_done_estimate=114.1 elapsed_s=11 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=162474 percent_done_estimate=117.1 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=166640 percent_done_estimate=120.1 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=170806 percent_done_estimate=123.1 elapsed_s=12 est_remaining_s=-5 / 7
tid=0 thread_prefill_amount=174972 percent_done_estimate=126.1 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=179138 percent_done_estimate=129.1 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=183304 percent_done_estimate=132.1 elapsed_s=12 est_remaining_s=-7 / 6
tid=0 thread_prefill_amount=187470 percent_done_estimate=135.1 elapsed_s=13 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=191636 percent_done_estimate=138.1 elapsed_s=13 est_remaining_s=-8 / 5
tid=0 thread_prefill_amount=195802 percent_done_estimate=141.1 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=199968 percent_done_estimate=144.1 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=204134 percent_done_estimate=147.1 elapsed_s=13 est_remaining_s=-10 / 4
timing_elapsed 8.10678s
prefilling_size=10000000 prefilling_elapsed_millis=8.126s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008939731718, performing 18338721 updates; total_prefilling_elapsed_ms=8126 ms)
pref_size=10000000
pref_millis=8126
prefill_elapsed_ms=8126
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=995
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6991430.92161311
REALTIME_END_PERF_FORMAT=6991440.92236061
###############################################################################
################################## 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 (48 threads)...
partitioned into 243 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.115s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 62 123 240 476 918 1769 3294 5921 10322 17310 28283 44449 67125 97203 136180 183150 236750 295171 352507 405237 447485 476755 487504 481486 459527 423939 380518 330206 279137 230553 185777 145820 111601 82658 59311 41511 28283 18723 12228 7815 4833 2920 1739 1025 580 326 174 84 40 21 9 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 2 0 3 2 21 45 140 351 815 1714 3341 6396 11345 19458 30350 46715 68111 93406 123822 156466 188923 217293 242350 256003 260590 256120 241385 222385 196511 168961 141917 116275 92519 72446 54591 39561 28077 19393 12922 8456 5498 3485 2116 1251 755 412 244 132 64 31 16 10 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 123 243 478 939 1814 3434 6272 11137 19024 31624 50845 78470 116661 166530 229865 304861 388577 476329 561703 636408 694048 729854 737489 720117 680059 621903 552591 475648 399514 327694 262095 204120 155104 113902 81072 56360 38116 25150 16271 10331 6405 3855 2276 1335 738 418 216 104 52 25 11 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 123 243 478 939 1814 3434 6272 11137 19024 31624 50845 78470 116661 166530 229865 304861 388577 476329 561703 636408 694048 729854 737489 720117 680059 621903 552591 475648 399514 327694 262095 204120 155104 113902 81072 56360 38116 25150 16271 10331 6405 3855 2276 1335 738 418 216 104 52 25 11 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.97561 1.97942 1.96862 1.95421 1.91786 1.86721 1.83163 1.78136 1.75242 1.71345 1.66911 1.63127 1.59426 1.56257 1.52949 1.49802 1.46621 1.43918 1.41155 1.38743 1.36467 1.34251 1.32357 1.30624 1.2911 1.27669 1.2632 1.25308 1.24315 1.23289 1.22244 1.21312 1.20144 1.19105 1.18316 1.17447 1.16862 1.16076 1.15463 1.15216 1.14598 1.1393 1.1362 1.11835 1.12466 1.10048 1.09259 1.11538 1.07692 1.08 1 1
tree_stats_height=59
tree_stats_numInternals=6589142
tree_stats_numLeaves=3413196
tree_stats_numNodes=10002338
tree_stats_numKeys=10002338
tree_stats_avgDegreeInternal=1.518
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34124
tree_stats_avgKeyDepth=29.314
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=11196
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:165 2:2714 4:62415 8:364144 16:50408
[2^00, 2^01]: 165
(2^01, 2^02]: 2714
(2^02, 2^03]: 62415
(2^03, 2^04]: 364144
(2^04, 2^05]: 50408
sum_limbo_reclamation_event_size_total=5367462
count_limbo_reclamation_event_size_by_thread=9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9993 9997 9997 9997 9998 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9991 9997 9997 9997 9997 9997 9997 9997 9997 9997
count_limbo_reclamation_event_size_total=479846
sum_limbo_reclamation_event_count_total=489695
first_thread_announced_epoch_by_thread=22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392 22392
sum_num_inserts_by_thread=227955 232307 235912 230871 236004 230351 221803 233403 235866 233690 227037 235145 233945 231971 147549 234218 232408 231435 229130 234230 229232 232899 230138 228769 229711 231769 236200 232307 235576 232346 225951 234159 233877 233300 230254 233683 232095 227684 154055 235861 232887 233295 232962 232011 230263 227240 229506 223123
sum_num_inserts_total=10956383
sum_num_deletes_by_thread=228107 231147 235272 229564 235923 230355 222577 235414 236439 233799 227176 235550 233689 232539 147131 234237 232853 231440 229748 233877 229121 233445 230188 229421 230282 232431 236168 230289 234714 232640 226281 233819 233578 232960 230175 233164 231073 228310 154386 234648 233665 232823 233689 233108 230444 227662 228703 222895
sum_num_deletes_total=10956919
sum_num_searches_by_thread=4116435 4171897 4246014 4142002 4236705 4149767 3995361 4224188 4251503 4207912 4081304 4245924 4212918 4188661 2648811 4220048 4196188 4175615 4127668 4211230 4127394 4208646 4135879 4126423 4142621 4166488 4251088 4163139 4234380 4182389 4068186 4214572 4209511 4201031 4140370 4202269 4169907 4097445 2776192 4240088 4189602 4191820 4198505 4185295 4145651 4093364 4124763 4008927
sum_num_searches_total=197246096
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4572497 4635351 4717198 4602437 4708632 4610473 4439741 4693005 4723808 4675401 4535517 4716619 4680552 4653171 2943491 4688503 4661449 4638490 4586546 4679337 4585747 4674990 4596205 4584613 4602614 4630688 4723456 4625735 4704670 4647375 4520418 4682550 4676966 4667291 4600799 4669116 4633075 4553439 3084633 4710597 4656154 4657938 4665156 4650414 4606358 4548266 4582972 4454945
average_num_operations_total=4565820
stdev_num_operations_total=48663
sum_num_operations_total=219159398
min_num_operations_total=2943491
max_num_operations_total=4723808
first_time_thread_terminate_by_thread=10000128 10000075 10000075 10000074 10000075 10000075 10000075 10000075 10000075 10000074 10000074 10000075 10000074 10000075 10000075 10000075 10000075 10000074 10000074 10000074 10000075 10000075 10000075 10000075 10000074 10000075 10000076 10000074 10000075 10000075 10000075 10000075 10000075 10000075 10000076 10000075 10000075 10000075 10000075 10000075 10000076 10000074 10000074 10000074 10000075 10000075 10000075 10000075
min_time_thread_terminate_total=10000074
max_time_thread_terminate_total=10000128
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=100028858337965
threads_final_size=10002338
final_keysum=100028858337965
final_size=10002338
validate_result=success
Validation OK.
Structural validation OK.
total_find=197246096
total_rq=0
total_inserts=10956383
total_deletes=10956919
total_updates=21913302
total_queries=197246096
total_ops=219159398
find_throughput=19724609
rq_throughput=0
update_throughput=2191330
query_throughput=19724609
total_throughput=21915939
total find : 197246096
total rq : 0
total inserts : 10956383
total deletes : 10956919
total updates : 21913302
total queries : 197246096
total ops : 219159398
find throughput : 19724609
rq throughput : 0
update throughput : 2191330
query throughput : 19724609
total throughput : 21915939
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=19.6853
PAPI_L3_TCM=6.08599
PAPI_TOT_CYC=6105.63
PAPI_TOT_INS=1696.34
garbage=985835125631811
total_execution_walltime=23.738s
REALTIME_MAIN_END_PERF_FORMAT=6991440.379476696
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 232586253
[slow htm]
[fallback]
total fallback commit : 4911867
seconds global lock is held : 0.624377
[time_cmd_output] time_elapsed_sec=23.89, faults_major=0, faults_minor=156033, mem_maxresident_kb=624780, user_cputime=1116.09, sys_cputime=3.06, percent_cpu=4684%
timeout=false
algorithm=brown_int_bst_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