__step=000091
__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_abtree_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=data000091.txt
__path_data=data/data000091.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994367.205300343
binary=./brown_abtree_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_abtree_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=200
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.03s
prefilling_size=299394 prefilling_elapsed_millis=1.001s
prefilling_size=328872 prefilling_elapsed_millis=1.101s
prefilling_size=358118 prefilling_elapsed_millis=1.201s
prefilling_size=387613 prefilling_elapsed_millis=1.301s
prefilling_size=416832 prefilling_elapsed_millis=1.402s
prefilling_size=445958 prefilling_elapsed_millis=1.502s
prefilling_size=474764 prefilling_elapsed_millis=1.602s
prefilling_size=503472 prefilling_elapsed_millis=1.702s
prefilling_size=532161 prefilling_elapsed_millis=1.802s
prefilling_size=560639 prefilling_elapsed_millis=1.903s
prefilling_size=589264 prefilling_elapsed_millis=2.003s
prefilling_size=617811 prefilling_elapsed_millis=2.103s
prefilling_size=646176 prefilling_elapsed_millis=2.203s
prefilling_size=674481 prefilling_elapsed_millis=2.303s
prefilling_size=702752 prefilling_elapsed_millis=2.404s
prefilling_size=730793 prefilling_elapsed_millis=2.504s
prefilling_size=758930 prefilling_elapsed_millis=2.604s
prefilling_size=786845 prefilling_elapsed_millis=2.704s
prefilling_size=814879 prefilling_elapsed_millis=2.804s
prefilling_size=842705 prefilling_elapsed_millis=2.905s
prefilling_size=870510 prefilling_elapsed_millis=3.005s
prefilling_size=898221 prefilling_elapsed_millis=3.105s
prefilling_size=925816 prefilling_elapsed_millis=3.205s
prefilling_size=953351 prefilling_elapsed_millis=3.305s
prefilling_size=980478 prefilling_elapsed_millis=3.406s
prefilling_size=1007560 prefilling_elapsed_millis=3.506s
prefilling_size=1034793 prefilling_elapsed_millis=3.606s
prefilling_size=1061905 prefilling_elapsed_millis=3.706s
prefilling_size=1089167 prefilling_elapsed_millis=3.806s
prefilling_size=1116097 prefilling_elapsed_millis=3.907s
prefilling_size=1142998 prefilling_elapsed_millis=4.007s
prefilling_size=1169875 prefilling_elapsed_millis=4.107s
prefilling_size=1196656 prefilling_elapsed_millis=4.207s
prefilling_size=1223288 prefilling_elapsed_millis=4.307s
prefilling_size=1249891 prefilling_elapsed_millis=4.408s
prefilling_size=1276261 prefilling_elapsed_millis=4.508s
prefilling_size=1302676 prefilling_elapsed_millis=4.608s
prefilling_size=1328944 prefilling_elapsed_millis=4.708s
prefilling_size=1355275 prefilling_elapsed_millis=4.808s
prefilling_size=1381260 prefilling_elapsed_millis=4.909s
prefilling_size=1407266 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8592620 keys with 96 threads...
tid=0 thread_prefill_amount=16664 percent_done_estimate=18.6 elapsed_s=5 est_remaining_s=53 / 59
tid=0 thread_prefill_amount=18747 percent_done_estimate=20.9 elapsed_s=6 est_remaining_s=51 / 57
tid=0 thread_prefill_amount=20830 percent_done_estimate=23.3 elapsed_s=7 est_remaining_s=49 / 55
tid=0 thread_prefill_amount=22913 percent_done_estimate=25.6 elapsed_s=7 est_remaining_s=47 / 54
tid=0 thread_prefill_amount=24996 percent_done_estimate=27.9 elapsed_s=8 est_remaining_s=45 / 53
tid=0 thread_prefill_amount=27079 percent_done_estimate=30.3 elapsed_s=8 est_remaining_s=43 / 52
tid=0 thread_prefill_amount=29162 percent_done_estimate=32.6 elapsed_s=9 est_remaining_s=42 / 51
tid=0 thread_prefill_amount=31245 percent_done_estimate=34.9 elapsed_s=10 est_remaining_s=40 / 50
tid=0 thread_prefill_amount=33328 percent_done_estimate=37.2 elapsed_s=10 est_remaining_s=38 / 49
tid=0 thread_prefill_amount=35411 percent_done_estimate=39.6 elapsed_s=11 est_remaining_s=37 / 48
tid=0 thread_prefill_amount=37494 percent_done_estimate=41.9 elapsed_s=11 est_remaining_s=35 / 47
tid=0 thread_prefill_amount=39577 percent_done_estimate=44.2 elapsed_s=12 est_remaining_s=34 / 46
tid=0 thread_prefill_amount=41660 percent_done_estimate=46.5 elapsed_s=13 est_remaining_s=32 / 45
tid=0 thread_prefill_amount=43743 percent_done_estimate=48.9 elapsed_s=13 est_remaining_s=31 / 44
tid=0 thread_prefill_amount=45826 percent_done_estimate=51.2 elapsed_s=14 est_remaining_s=30 / 43
tid=0 thread_prefill_amount=47909 percent_done_estimate=53.5 elapsed_s=14 est_remaining_s=28 / 42
tid=0 thread_prefill_amount=49992 percent_done_estimate=55.9 elapsed_s=15 est_remaining_s=27 / 42
tid=0 thread_prefill_amount=52075 percent_done_estimate=58.2 elapsed_s=16 est_remaining_s=25 / 41
tid=0 thread_prefill_amount=54158 percent_done_estimate=60.5 elapsed_s=16 est_remaining_s=24 / 40
tid=0 thread_prefill_amount=56241 percent_done_estimate=62.8 elapsed_s=17 est_remaining_s=22 / 39
tid=0 thread_prefill_amount=58324 percent_done_estimate=65.2 elapsed_s=17 est_remaining_s=21 / 38
tid=0 thread_prefill_amount=60407 percent_done_estimate=67.5 elapsed_s=18 est_remaining_s=19 / 37
tid=0 thread_prefill_amount=62490 percent_done_estimate=69.8 elapsed_s=19 est_remaining_s=18 / 37
tid=0 thread_prefill_amount=64573 percent_done_estimate=72.1 elapsed_s=19 est_remaining_s=17 / 36
tid=0 thread_prefill_amount=66656 percent_done_estimate=74.5 elapsed_s=20 est_remaining_s=15 / 35
tid=0 thread_prefill_amount=68739 percent_done_estimate=76.8 elapsed_s=20 est_remaining_s=14 / 34
tid=0 thread_prefill_amount=70822 percent_done_estimate=79.1 elapsed_s=21 est_remaining_s=12 / 33
tid=0 thread_prefill_amount=72905 percent_done_estimate=81.5 elapsed_s=22 est_remaining_s=11 / 33
tid=0 thread_prefill_amount=74988 percent_done_estimate=83.8 elapsed_s=22 est_remaining_s=10 / 32
tid=0 thread_prefill_amount=77071 percent_done_estimate=86.1 elapsed_s=23 est_remaining_s=8 / 31
tid=0 thread_prefill_amount=79154 percent_done_estimate=88.4 elapsed_s=23 est_remaining_s=7 / 30
tid=0 thread_prefill_amount=81237 percent_done_estimate=90.8 elapsed_s=24 est_remaining_s=5 / 29
tid=0 thread_prefill_amount=83320 percent_done_estimate=93.1 elapsed_s=25 est_remaining_s=4 / 29
tid=0 thread_prefill_amount=85403 percent_done_estimate=95.4 elapsed_s=25 est_remaining_s=3 / 28
tid=0 thread_prefill_amount=87486 percent_done_estimate=97.7 elapsed_s=26 est_remaining_s=1 / 27
tid=0 thread_prefill_amount=89569 percent_done_estimate=100.1 elapsed_s=26 est_remaining_s=-0 / 26
tid=0 thread_prefill_amount=91652 percent_done_estimate=102.4 elapsed_s=27 est_remaining_s=-1 / 25
tid=0 thread_prefill_amount=93735 percent_done_estimate=104.7 elapsed_s=27 est_remaining_s=-3 / 25
tid=0 thread_prefill_amount=95818 percent_done_estimate=107.1 elapsed_s=28 est_remaining_s=-4 / 24
tid=0 thread_prefill_amount=97901 percent_done_estimate=109.4 elapsed_s=29 est_remaining_s=-6 / 23
tid=0 thread_prefill_amount=99984 percent_done_estimate=111.7 elapsed_s=29 est_remaining_s=-7 / 22
tid=0 thread_prefill_amount=102067 percent_done_estimate=114.0 elapsed_s=30 est_remaining_s=-8 / 22
tid=0 thread_prefill_amount=104150 percent_done_estimate=116.4 elapsed_s=30 est_remaining_s=-10 / 21
timing_elapsed 27.6764s
prefilling_size=10000000 prefilling_elapsed_millis=27.742s
finished prefilling to size 10000000 for expected size 10000000 keysum=100024885809302, performing 15442999 updates; total_prefilling_elapsed_ms=27742 ms)
pref_size=10000000
pref_millis=27742
prefill_elapsed_ms=27743
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6994400.300171075
REALTIME_END_PERF_FORMAT=6994410.300272544

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

tree_stats_computeWalltime=0.011s

tree_stats_numInternalsAtDepth=1 1 8 56 405 3007 22776 176714 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1395385
tree_stats_numNodesAtDepth=1 1 8 56 405 3007 22776 176714 1395385
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001592
tree_stats_avgDegreeAtDepth=1 8 7 7.23214 7.42469 7.57433 7.75878 7.89629 7.16762

tree_stats_height=9
tree_stats_numInternals=202968
tree_stats_numLeaves=1395385
tree_stats_numNodes=1598353
tree_stats_numKeys=10001592

tree_stats_avgDegreeInternal=7.8749
tree_stats_avgDegreeLeaves=7.16762
tree_stats_avgDegree=7.25744
tree_stats_avgKeyDepth=8


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=95230
first_thread_announced_epoch_by_thread=2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394 2394
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=

linear_histogram_of_none_tx_rdset_size_full_data=

linear_histogram_of_none_tx_wrset_size_full_data=
sum_fasthtm_commit_total=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
sum_htmprefix_commit_total=
sum_htmprefix_abort_total=
sum_htmprefix_abort_explicit_total=
sum_htmprefix_abort_conflict_total=
sum_htmprefix_abort_capacity_total=
sum_htmprefix_abort_nesting_total=
sum_htmprefix_abort_illegal_total=
sum_htmprefix_abort_zero_total=
sum_stmprefix_abort_total=
sum_stmprefix_commit_total=
sum_slow_commit_total=
sum_abtree_insert_repeat_total=
sum_abtree_erase_repeat_total=
sum_abtree_search_repeat_total=
sum_abtree_search_complete_total=
sum_abtree_fix_weight_viol_attempt_total=
sum_abtree_fix_degree_viol_attempt_total=
sum_abtree_rebalancing_kcas_attempt_total=
sum_abtree_update_kcas_attempt_total=
sum_abtree_internal_complex_search_while_iters_total=
sum_abtree_internal_basic_search_while_iters_total=
sum_kcas_desc_new_total=
sum_num_inserts_by_thread=34989 33022 32674 32370 32499 32518 34763 32949 32493 32444 32152 32400 34366 33099 32752 33168 32440 32372 34490 32588 32196 32369 32132 32017 35242 33354 32720 32928 32839 32691 34505 33011 32647 32599 32229 32559 34614 33135 32953 33135 32707 32306 34636 32013 32755 32547 32350 31851 34990 33091 32681 32645 32980 32539 34219 32883 32287 32991 32237 32215 34410 32623 33022 32938 32492 32410 34420 32394 32483 32816 31883 32516 34643 32961 32617 32728 32010 32400 34317 32843 32570 32559 32432 31665 34742 32538 33061 32992 33136 32753 34162 32642 32564 32620 32095 32431
sum_num_inserts_total=3161234
sum_num_deletes_by_thread=35190 33132 33131 32367 32888 32751 34882 33015 32286 32512 31716 32721 34374 32798 32479 33324 32689 31877 34951 32581 31773 32510 32048 31977 34969 33008 32427 32689 32482 32600 34465 32815 33007 32663 32452 32397 34197 33196 33325 32953 32890 32297 34610 32048 32728 32753 32365 32459 35093 32965 32539 32789 32998 32660 34521 32624 32432 32651 32645 31799 34425 32679 32755 32688 32381 32388 34324 32293 32595 33218 32271 32374 34673 32896 32817 33083 32288 32069 34957 32661 32407 32503 32261 31591 34435 32444 33221 33018 32808 32373 34551 32590 32528 32418 32255 31958
sum_num_deletes_total=3160679
sum_num_searches_by_thread=633078 593194 590326 586765 593771 585690 626691 594237 584116 586725 576085 584122 622141 587004 585095 597174 586139 575737 618780 581300 577066 581381 579990 577513 630251 596844 585113 590394 591589 585608 628326 592054 589052 587684 593382 584579 615921 596697 598989 595345 591621 584219 624323 575750 592470 587665 584283 576638 636499 596207 587703 590370 594842 586181 618380 586540 582725 592595 584077 575898 618362 587325 591160 589682 585865 584678 617779 583154 586586 595807 578157 583328 623276 597789 590113 595573 579165 578868 621718 586312 584255 583683 582012 566906 623124 583100 599864 596484 592445 587381 620561 590576 589178 586050 581717 576229
sum_num_searches_total=56933196
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=703257 659348 656131 651502 659158 650959 696336 660201 648895 651681 639953 649243 690881 652901 650326 663666 651268 639986 688221 646469 641035 646260 644170 641507 700462 663206 650260 656011 656910 650899 697296 657880 654706 652946 658063 649535 684732 663028 665267 661433 657218 648822 693569 639811 657953 652965 648998 640948 706582 662263 652923 655804 660820 651380 687120 652047 647444 658237 648959 639912 687197 652627 656937 655308 650738 649476 686523 647841 651664 661841 642311 648218 692592 663646 655547 661384 643463 643337 690992 651816 649232 648745 646705 630162 692301 648082 666146 662494 658389 652507 689274 655808 654270 651088 646067 640618
average_num_operations_total=658907
stdev_num_operations_total=1729
sum_num_operations_total=63255109
min_num_operations_total=630162
max_num_operations_total=706582
first_time_thread_terminate_by_thread=10000267 10000156 10000102 10000252 10000259 10000114 10000254 10000160 10000204 10000174 10000125 10000197 10000150 10000241 10000101 10000143 10000217 10000140 10000144 10000219 10000165 10000249 10000228 10000236 10000103 10000216 10000171 10000248 10000240 10000175 10000239 10000162 10000223 10000159 10000214 10000103 10000226 10000098 10000205 10000231 10000169 10000180 10000251 10000194 10000154 10000235 10000230 10000237 10000099 10000201 10000191 10000098 10000256 10000258 10000183 10000132 10000221 10000255 10000190 10000178 10000263 10000098 10000210 10000209 10000098 10000117 10000103 10000224 10000198 10000244 10000098 10000242 10000181 10000201 10000141 10000128 10000136 10000186 10000099 10000130 10000246 10000256 10000202 10000222 10000188 10000098 10000247 10000206 10000132 10000110 10000098 10000245 10000105 10000258 10000098 10000121
min_time_thread_terminate_total=10000098
max_time_thread_terminate_total=10000267
first_time_thread_start_by_thread=12 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 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 6 6 5 6 5 5 5 5 5 5 5 5 5 5 6 5 5 5 6 5 5 5 5 5 6 6 5 6 5 5 5 5 5 5 5 5 5 5 6 5 5
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100041142422245
threads_final_size=10001592
final_keysum=100041142422245
final_size=10001592
validate_result=success
Validation OK.
Structural validation OK.

total_find=56933196
total_rq=0
total_inserts=3161234
total_deletes=3160679
total_updates=6321913
total_queries=56933196
total_ops=63255109
find_throughput=5693319
rq_throughput=0
update_throughput=632191
query_throughput=5693319
total_throughput=6325510


total find                    : 56933196
total rq                      : 0
total inserts                 : 3161234
total deletes                 : 3160679
total updates                 : 6321913
total queries                 : 56933196
total ops                     : 63255109
find throughput               : 5693319
rq throughput                 : 0
update throughput             : 632191
query throughput              : 5693319
total throughput              : 6325510

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=28.3679
PAPI_L3_TCM=8.4049
PAPI_TOT_CYC=42052.1
PAPI_TOT_INS=5671.62
garbage=281744780144206
total_execution_walltime=43.225s
REALTIME_MAIN_END_PERF_FORMAT=6994410.431325789
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 68080045
[slow htm]
[fallback]
total fallback commit         : 10618064
seconds global lock is held   : 2.10092
[time_cmd_output] time_elapsed_sec=43.46, faults_major=0, faults_minor=428409, mem_maxresident_kb=1710600, user_cputime=4080.69, sys_cputime=10.29, percent_cpu=9412%
timeout=false
algorithm=brown_abtree_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