__step=000050
__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 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=data000050.txt
__path_data=data/data000050.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992775.636760703
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=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=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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=813337 prefilling_elapsed_millis=1.001s
prefilling_size=890481 prefilling_elapsed_millis=1.101s
prefilling_size=966850 prefilling_elapsed_millis=1.201s
prefilling_size=1042517 prefilling_elapsed_millis=1.302s
prefilling_size=1117422 prefilling_elapsed_millis=1.402s
prefilling_size=1191743 prefilling_elapsed_millis=1.502s
prefilling_size=1265553 prefilling_elapsed_millis=1.602s
prefilling_size=1338632 prefilling_elapsed_millis=1.702s
prefilling_size=1410921 prefilling_elapsed_millis=1.802s
prefilling_size=1482861 prefilling_elapsed_millis=1.903s
prefilling_size=1553934 prefilling_elapsed_millis=2.003s
prefilling_size=1624532 prefilling_elapsed_millis=2.103s
prefilling_size=1694305 prefilling_elapsed_millis=2.203s
prefilling_size=1763392 prefilling_elapsed_millis=2.303s
prefilling_size=1832028 prefilling_elapsed_millis=2.404s
prefilling_size=1900405 prefilling_elapsed_millis=2.504s
prefilling_size=1968133 prefilling_elapsed_millis=2.604s
prefilling_size=2035064 prefilling_elapsed_millis=2.704s
prefilling_size=2101269 prefilling_elapsed_millis=2.804s
prefilling_size=2166630 prefilling_elapsed_millis=2.905s
prefilling_size=2231505 prefilling_elapsed_millis=3.005s
prefilling_size=2296235 prefilling_elapsed_millis=3.105s
prefilling_size=2360016 prefilling_elapsed_millis=3.205s
prefilling_size=2423061 prefilling_elapsed_millis=3.305s
prefilling_size=2485483 prefilling_elapsed_millis=3.406s
prefilling_size=2547315 prefilling_elapsed_millis=3.506s
prefilling_size=2608538 prefilling_elapsed_millis=3.606s
prefilling_size=2669665 prefilling_elapsed_millis=3.706s
prefilling_size=2730139 prefilling_elapsed_millis=3.807s
prefilling_size=2790483 prefilling_elapsed_millis=3.907s
prefilling_size=2849975 prefilling_elapsed_millis=4.007s
prefilling_size=2909299 prefilling_elapsed_millis=4.107s
prefilling_size=2967590 prefilling_elapsed_millis=4.207s
prefilling_size=3025323 prefilling_elapsed_millis=4.308s
prefilling_size=3082816 prefilling_elapsed_millis=4.408s
prefilling_size=3139854 prefilling_elapsed_millis=4.508s
prefilling_size=3196141 prefilling_elapsed_millis=4.608s
prefilling_size=3252100 prefilling_elapsed_millis=4.708s
prefilling_size=3307256 prefilling_elapsed_millis=4.809s
prefilling_size=3362079 prefilling_elapsed_millis=4.909s
prefilling_size=3417035 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6582882 keys with 48 threads...
tid=0 thread_prefill_amount=66656 percent_done_estimate=48.6 elapsed_s=5 est_remaining_s=12 / 17
tid=0 thread_prefill_amount=70822 percent_done_estimate=51.6 elapsed_s=5 est_remaining_s=11 / 17
tid=0 thread_prefill_amount=74988 percent_done_estimate=54.7 elapsed_s=6 est_remaining_s=11 / 16
tid=0 thread_prefill_amount=79154 percent_done_estimate=57.7 elapsed_s=6 est_remaining_s=10 / 16
tid=0 thread_prefill_amount=83320 percent_done_estimate=60.8 elapsed_s=6 est_remaining_s=9 / 15
tid=0 thread_prefill_amount=87486 percent_done_estimate=63.8 elapsed_s=6 est_remaining_s=8 / 15
tid=0 thread_prefill_amount=91652 percent_done_estimate=66.8 elapsed_s=7 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=95818 percent_done_estimate=69.9 elapsed_s=7 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=99984 percent_done_estimate=72.9 elapsed_s=7 est_remaining_s=6 / 13
tid=0 thread_prefill_amount=104150 percent_done_estimate=75.9 elapsed_s=7 est_remaining_s=5 / 13
tid=0 thread_prefill_amount=108316 percent_done_estimate=79.0 elapsed_s=8 est_remaining_s=5 / 12
tid=0 thread_prefill_amount=112482 percent_done_estimate=82.0 elapsed_s=8 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=116648 percent_done_estimate=85.1 elapsed_s=8 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=120814 percent_done_estimate=88.1 elapsed_s=8 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=124980 percent_done_estimate=91.1 elapsed_s=9 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=129146 percent_done_estimate=94.2 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=133312 percent_done_estimate=97.2 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=137478 percent_done_estimate=100.2 elapsed_s=9 est_remaining_s=-0 / 9
tid=0 thread_prefill_amount=141644 percent_done_estimate=103.3 elapsed_s=10 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=145810 percent_done_estimate=106.3 elapsed_s=10 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=149976 percent_done_estimate=109.4 elapsed_s=10 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=154142 percent_done_estimate=112.4 elapsed_s=10 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=158308 percent_done_estimate=115.4 elapsed_s=11 est_remaining_s=-3 / 7
tid=0 thread_prefill_amount=162474 percent_done_estimate=118.5 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=166640 percent_done_estimate=121.5 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=170806 percent_done_estimate=124.5 elapsed_s=11 est_remaining_s=-5 / 6
tid=0 thread_prefill_amount=174972 percent_done_estimate=127.6 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=179138 percent_done_estimate=130.6 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=183304 percent_done_estimate=133.7 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=187470 percent_done_estimate=136.7 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=191636 percent_done_estimate=139.7 elapsed_s=13 est_remaining_s=-8 / 5
tid=0 thread_prefill_amount=195802 percent_done_estimate=142.8 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=199968 percent_done_estimate=145.8 elapsed_s=13 est_remaining_s=-9 / 4
timing_elapsed 7.67783s
prefilling_size=10000000 prefilling_elapsed_millis=7.696s
finished prefilling to size 10000000 for expected size 10000000 keysum=100000586635668, performing 18670836 updates; total_prefilling_elapsed_ms=7696 ms)
pref_size=10000000
pref_millis=7696
prefill_elapsed_ms=7696
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992788.596161133
REALTIME_END_PERF_FORMAT=6992798.596228482

###############################################################################
################################## 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 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.047s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262015 519512 967809 1447744 1391506 703117 155239 10661 108 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 123 4082 61700 401322 1170035 1526806 868463 197374 13446 123
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262138 523594 1029509 1849066 2561541 2229923 1023702 208035 13554 123
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262138 523594 1029509 1849066 2561541 2229923 1023702 208035 13554 123
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99996 1.99787 1.97403 1.856 1.60236 1.32731 1.14377 1.05157 1.01391 1.00111 1

tree_stats_height=28
tree_stats_numInternals=5719853
tree_stats_numLeaves=4243475
tree_stats_numNodes=9963328
tree_stats_numKeys=9963328

tree_stats_avgDegreeInternal=1.74189
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42591
tree_stats_avgKeyDepth=21.6844


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=479519
first_thread_announced_epoch_by_thread=21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920 21920
sum_num_inserts_by_thread=227418 228418 229421 229184 228638 226365 227769 228592 229328 229079 228180 228219 228294 226510 230786 230222 228768 229079 224070 225704 230158 228062 225088 226909 227686 229314 228200 227514 226349 225781 226216 228273 229086 229090 226826 227569 225298 228593 228628 230982 229403 226018 227494 225260 229983 230235 227820 224719
sum_num_inserts_total=10940598
sum_num_deletes_by_thread=227730 227987 230176 229804 228220 226739 228159 229032 229396 229143 228511 227675 228315 227689 230767 230549 228873 227783 224576 226825 229897 228299 226423 226684 228791 228938 227749 228084 227678 225313 225549 229740 228629 229211 226355 227699 226585 228732 228740 230419 229174 225533 227214 225741 229364 231526 228412 224888
sum_num_deletes_total=10949316
sum_num_searches_by_thread=4093270 4114468 4135363 4133189 4116160 4085240 4103401 4121358 4118237 4119499 4115601 4096039 4107771 4077417 4155383 4151551 4126947 4113963 4034306 4070938 4131898 4105040 4069169 4081179 4101437 4115873 4099422 4099915 4085345 4049227 4060098 4120058 4112936 4120859 4069636 4095786 4071417 4126043 4109845 4148013 4126469 4070172 4084343 4066958 4128251 4145037 4104009 4038630
sum_num_searches_total=196927166
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4548418 4570873 4594960 4592177 4573018 4538344 4559329 4578982 4576961 4577721 4572292 4551933 4564380 4531616 4616936 4612322 4584588 4570825 4482952 4523467 4591953 4561401 4520680 4534772 4557914 4574125 4555371 4555513 4539372 4500321 4511863 4578071 4570651 4579160 4522817 4551054 4523300 4583368 4567213 4609414 4585046 4521723 4539051 4517959 4587598 4606798 4560241 4488237
average_num_operations_total=4558689
stdev_num_operations_total=4654
sum_num_operations_total=218817080
min_num_operations_total=4482952
max_num_operations_total=4616936
first_time_thread_terminate_by_thread=10000120 10000067 10000068 10000067 10000067 10000067 10000068 10000067 10000067 10000068 10000067 10000068 10000068 10000067 10000067 10000067 10000068 10000067 10000068 10000067 10000067 10000068 10000067 10000068 10000067 10000067 10000068 10000067 10000069 10000067 10000067 10000067 10000067 10000067 10000068 10000067 10000067 10000068 10000067 10000067 10000068 10000067 10000067 10000067 10000067 10000068 10000067 10000068
min_time_thread_terminate_total=10000067
max_time_thread_terminate_total=10000120
first_time_thread_start_by_thread=9 5 6 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 6 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99623452291982
threads_final_size=9963328
final_keysum=99623452291982
final_size=9963328
validate_result=success
Validation OK.
Structural validation OK.

total_find=196927166
total_rq=0
total_inserts=10940598
total_deletes=10949316
total_updates=21889914
total_queries=196927166
total_ops=218817080
find_throughput=19692716
rq_throughput=0
update_throughput=2188991
query_throughput=19692716
total_throughput=21881708


total find                    : 196927166
total rq                      : 0
total inserts                 : 10940598
total deletes                 : 10949316
total updates                 : 21889914
total queries                 : 196927166
total ops                     : 218817080
find throughput               : 19692716
rq throughput                 : 0
update throughput             : 2188991
query throughput              : 19692716
total throughput              : 21881708

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=21.4073
PAPI_L3_TCM=7.07727
PAPI_TOT_CYC=6112.2
PAPI_TOT_INS=1269.6
garbage=987706456876967
total_execution_walltime=23.159s
REALTIME_MAIN_END_PERF_FORMAT=6992798.797224015
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 233454813
[slow htm]
[fallback]
total fallback commit         : 4033104
seconds global lock is held   : 1.32642
[time_cmd_output] time_elapsed_sec=23.36, faults_major=0, faults_minor=263965, mem_maxresident_kb=1056244, user_cputime=1099.88, sys_cputime=3.37, percent_cpu=4722%
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