__step=000092
__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=data000092.txt
__path_data=data/data000092.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994410.752871622
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.04s
prefilling_size=317724 prefilling_elapsed_millis=1s
prefilling_size=348805 prefilling_elapsed_millis=1.101s
prefilling_size=379991 prefilling_elapsed_millis=1.201s
prefilling_size=410869 prefilling_elapsed_millis=1.301s
prefilling_size=441726 prefilling_elapsed_millis=1.401s
prefilling_size=472622 prefilling_elapsed_millis=1.502s
prefilling_size=503202 prefilling_elapsed_millis=1.602s
prefilling_size=533854 prefilling_elapsed_millis=1.702s
prefilling_size=564228 prefilling_elapsed_millis=1.802s
prefilling_size=594628 prefilling_elapsed_millis=1.902s
prefilling_size=624873 prefilling_elapsed_millis=2.003s
prefilling_size=655023 prefilling_elapsed_millis=2.103s
prefilling_size=685147 prefilling_elapsed_millis=2.203s
prefilling_size=715190 prefilling_elapsed_millis=2.303s
prefilling_size=745089 prefilling_elapsed_millis=2.404s
prefilling_size=774691 prefilling_elapsed_millis=2.504s
prefilling_size=804409 prefilling_elapsed_millis=2.604s
prefilling_size=833905 prefilling_elapsed_millis=2.704s
prefilling_size=863264 prefilling_elapsed_millis=2.805s
prefilling_size=892717 prefilling_elapsed_millis=2.905s
prefilling_size=921819 prefilling_elapsed_millis=3.005s
prefilling_size=950978 prefilling_elapsed_millis=3.105s
prefilling_size=979927 prefilling_elapsed_millis=3.206s
prefilling_size=1008894 prefilling_elapsed_millis=3.306s
prefilling_size=1037741 prefilling_elapsed_millis=3.406s
prefilling_size=1066482 prefilling_elapsed_millis=3.506s
prefilling_size=1095014 prefilling_elapsed_millis=3.607s
prefilling_size=1123628 prefilling_elapsed_millis=3.707s
prefilling_size=1152240 prefilling_elapsed_millis=3.807s
prefilling_size=1180816 prefilling_elapsed_millis=3.907s
prefilling_size=1209257 prefilling_elapsed_millis=4.007s
prefilling_size=1237497 prefilling_elapsed_millis=4.108s
prefilling_size=1265530 prefilling_elapsed_millis=4.208s
prefilling_size=1293597 prefilling_elapsed_millis=4.308s
prefilling_size=1321350 prefilling_elapsed_millis=4.408s
prefilling_size=1349332 prefilling_elapsed_millis=4.509s
prefilling_size=1377265 prefilling_elapsed_millis=4.609s
prefilling_size=1405108 prefilling_elapsed_millis=4.709s
prefilling_size=1432705 prefilling_elapsed_millis=4.81s
prefilling_size=1460089 prefilling_elapsed_millis=4.91s
prefilling_size=1487523 prefilling_elapsed_millis=5.01s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8512343 keys with 96 threads...
tid=0 thread_prefill_amount=18747 percent_done_estimate=21.1 elapsed_s=6 est_remaining_s=47 / 53
tid=0 thread_prefill_amount=20830 percent_done_estimate=23.5 elapsed_s=6 est_remaining_s=46 / 52
tid=0 thread_prefill_amount=22913 percent_done_estimate=25.8 elapsed_s=7 est_remaining_s=44 / 51
tid=0 thread_prefill_amount=24996 percent_done_estimate=28.2 elapsed_s=7 est_remaining_s=42 / 50
tid=0 thread_prefill_amount=27079 percent_done_estimate=30.5 elapsed_s=8 est_remaining_s=41 / 49
tid=0 thread_prefill_amount=29162 percent_done_estimate=32.9 elapsed_s=9 est_remaining_s=39 / 48
tid=0 thread_prefill_amount=31245 percent_done_estimate=35.2 elapsed_s=9 est_remaining_s=38 / 47
tid=0 thread_prefill_amount=33328 percent_done_estimate=37.6 elapsed_s=10 est_remaining_s=36 / 46
tid=0 thread_prefill_amount=35411 percent_done_estimate=39.9 elapsed_s=10 est_remaining_s=35 / 45
tid=0 thread_prefill_amount=37494 percent_done_estimate=42.3 elapsed_s=11 est_remaining_s=33 / 44
tid=0 thread_prefill_amount=39577 percent_done_estimate=44.6 elapsed_s=12 est_remaining_s=32 / 44
tid=0 thread_prefill_amount=41660 percent_done_estimate=47.0 elapsed_s=12 est_remaining_s=31 / 43
tid=0 thread_prefill_amount=43743 percent_done_estimate=49.3 elapsed_s=13 est_remaining_s=29 / 42
tid=0 thread_prefill_amount=45826 percent_done_estimate=51.7 elapsed_s=13 est_remaining_s=28 / 41
tid=0 thread_prefill_amount=47909 percent_done_estimate=54.0 elapsed_s=14 est_remaining_s=26 / 40
tid=0 thread_prefill_amount=49992 percent_done_estimate=56.4 elapsed_s=14 est_remaining_s=25 / 39
tid=0 thread_prefill_amount=52075 percent_done_estimate=58.7 elapsed_s=15 est_remaining_s=24 / 39
tid=0 thread_prefill_amount=54158 percent_done_estimate=61.1 elapsed_s=16 est_remaining_s=22 / 38
tid=0 thread_prefill_amount=56241 percent_done_estimate=63.4 elapsed_s=16 est_remaining_s=21 / 37
tid=0 thread_prefill_amount=58324 percent_done_estimate=65.8 elapsed_s=17 est_remaining_s=20 / 36
tid=0 thread_prefill_amount=60407 percent_done_estimate=68.1 elapsed_s=17 est_remaining_s=18 / 36
tid=0 thread_prefill_amount=62490 percent_done_estimate=70.5 elapsed_s=18 est_remaining_s=17 / 35
tid=0 thread_prefill_amount=64573 percent_done_estimate=72.8 elapsed_s=19 est_remaining_s=16 / 34
tid=0 thread_prefill_amount=66656 percent_done_estimate=75.2 elapsed_s=19 est_remaining_s=14 / 33
tid=0 thread_prefill_amount=68739 percent_done_estimate=77.5 elapsed_s=20 est_remaining_s=13 / 33
tid=0 thread_prefill_amount=70822 percent_done_estimate=79.9 elapsed_s=20 est_remaining_s=12 / 32
tid=0 thread_prefill_amount=72905 percent_done_estimate=82.2 elapsed_s=21 est_remaining_s=10 / 31
tid=0 thread_prefill_amount=74988 percent_done_estimate=84.6 elapsed_s=21 est_remaining_s=9 / 30
tid=0 thread_prefill_amount=77071 percent_done_estimate=86.9 elapsed_s=22 est_remaining_s=7 / 30
tid=0 thread_prefill_amount=79154 percent_done_estimate=89.3 elapsed_s=23 est_remaining_s=6 / 29
tid=0 thread_prefill_amount=81237 percent_done_estimate=91.6 elapsed_s=23 est_remaining_s=5 / 28
tid=0 thread_prefill_amount=83320 percent_done_estimate=94.0 elapsed_s=24 est_remaining_s=3 / 27
tid=0 thread_prefill_amount=85403 percent_done_estimate=96.3 elapsed_s=24 est_remaining_s=2 / 27
tid=0 thread_prefill_amount=87486 percent_done_estimate=98.7 elapsed_s=25 est_remaining_s=1 / 26
tid=0 thread_prefill_amount=89569 percent_done_estimate=101.0 elapsed_s=26 est_remaining_s=-1 / 25
tid=0 thread_prefill_amount=91652 percent_done_estimate=103.4 elapsed_s=26 est_remaining_s=-2 / 24
tid=0 thread_prefill_amount=93735 percent_done_estimate=105.7 elapsed_s=27 est_remaining_s=-3 / 23
tid=0 thread_prefill_amount=95818 percent_done_estimate=108.1 elapsed_s=27 est_remaining_s=-5 / 23
tid=0 thread_prefill_amount=97901 percent_done_estimate=110.4 elapsed_s=28 est_remaining_s=-6 / 22
tid=0 thread_prefill_amount=99984 percent_done_estimate=112.8 elapsed_s=29 est_remaining_s=-7 / 21
tid=0 thread_prefill_amount=102067 percent_done_estimate=115.1 elapsed_s=29 est_remaining_s=-9 / 21
tid=0 thread_prefill_amount=104150 percent_done_estimate=117.5 elapsed_s=30 est_remaining_s=-10 / 20
timing_elapsed 25.2985s
prefilling_size=10000000 prefilling_elapsed_millis=25.359s
finished prefilling to size 10000000 for expected size 10000000 keysum=99993414864161, performing 15532713 updates; total_prefilling_elapsed_ms=25359 ms)
pref_size=10000000
pref_millis=25359
prefill_elapsed_ms=25359
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6994441.460162817
REALTIME_END_PERF_FORMAT=6994451.460272162
###############################################################################
################################## 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 406 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.01s
tree_stats_numInternalsAtDepth=1 1 8 55 406 3043 22783 177116 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1398590
tree_stats_numNodesAtDepth=1 1 8 55 406 3043 22783 177116 1398590
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001609
tree_stats_avgDegreeAtDepth=1 8 6.875 7.38182 7.49507 7.48702 7.77404 7.89646 7.15121
tree_stats_height=9
tree_stats_numInternals=203413
tree_stats_numLeaves=1398590
tree_stats_numNodes=1602003
tree_stats_numKeys=10001609
tree_stats_avgDegreeInternal=7.87562
tree_stats_avgDegreeLeaves=7.15121
tree_stats_avgDegree=7.24319
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=104638
first_thread_announced_epoch_by_thread=2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624 2624
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=38161 37903 37929 38089 37826 37205 38914 38091 38553 38667 37733 38080 39046 37942 37910 38034 37826 38142 37753 38495 37906 38811 37904 37509 37972 37790 37844 37671 37139 37432 38341 38795 38040 38671 38040 38292 38224 38223 37967 38246 37843 38661 37311 37847 38251 38267 38348 38059 31369 30705 30487 30433 30036 30301 30578 30401 30853 30386 30150 30207 30664 30784 30301 30413 30452 29831 30413 30294 30426 30602 30754 30637 31185 30389 30670 30309 29644 30430 30597 30672 30838 30778 30437 30704 30778 30626 30439 30104 30045 30154 30300 30282 30531 30226 30928 30537
sum_num_inserts_total=3290783
sum_num_deletes_by_thread=37967 37858 37866 37740 37760 37550 38685 37814 38766 38798 37953 37984 38752 37961 37828 38120 38132 38312 37359 38389 38294 38908 37945 37720 37967 38050 38184 37966 36905 37382 38235 38303 38184 38466 38329 38352 38256 38130 37894 38494 38126 38280 37698 37745 37924 38192 38279 37568 30985 30726 30571 30130 29922 30450 30743 30226 31006 30556 30319 29954 30502 30961 30489 30174 30488 29712 30807 29940 30370 30407 30825 30475 30884 30403 30160 30168 29992 30554 30731 30370 31078 30775 30256 30120 30679 30877 30405 30058 30285 30030 30447 30601 30600 30447 30695 30497
sum_num_deletes_total=3289220
sum_num_searches_by_thread=682944 685838 683253 679959 677091 670633 705936 684565 698843 696214 682475 686450 696248 681292 679054 681921 685840 689985 675136 690630 683570 701669 681561 678285 684238 683149 685274 679038 666953 670622 692185 697363 686621 695337 688597 695060 694656 686308 682102 685623 682188 696669 677116 678520 685195 688826 689658 676187 561053 551921 550212 547945 543841 545362 549308 545442 556409 549210 546810 541669 554107 558101 548121 547812 548529 537769 547397 538025 547555 552263 559525 548872 558349 546471 549880 543123 540379 544201 554011 548772 554032 554405 546082 547178 550256 555259 548753 544955 545297 542376 546552 547114 552396 545301 555388 548024
sum_num_searches_total=59252689
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=759072 761599 759048 755788 752677 745388 783535 760470 776162 773679 758161 762514 774046 757195 754792 758075 761798 766439 750248 767514 759770 779388 757410 753514 760177 758989 761302 754675 740997 745436 768761 774461 762845 772474 764966 771704 771136 762661 757963 762363 758157 773610 752125 754112 761370 765285 766285 751814 623407 613352 611270 608508 603799 606113 610629 606069 618268 610152 607279 601830 615273 619846 608911 608399 609469 597312 608617 598259 608351 613272 621104 609984 620418 607263 610710 603600 600015 605185 615339 609814 615948 615958 606775 608002 611713 616762 609597 605117 605627 602560 607299 607997 613527 605974 617011 609058
average_num_operations_total=685757
stdev_num_operations_total=7871
sum_num_operations_total=65832692
min_num_operations_total=597312
max_num_operations_total=783535
first_time_thread_terminate_by_thread=10000188 10000108 10000224 10000194 10000171 10000138 10000128 10000109 10000206 10000172 10000194 10000161 10000115 10000228 10000227 10000251 10000245 10000132 10000129 10000254 10000155 10000109 10000117 10000184 10000179 10000112 10000225 10000204 10000182 10000175 10000189 10000124 10000250 10000181 10000241 10000137 10000242 10000243 10000192 10000203 10000110 10000163 10000207 10000157 10000109 10000109 10000190 10000125 10000222 10000109 10000166 10000209 10000231 10000209 10000217 10000234 10000240 10000201 10000108 10000210 10000149 10000213 10000109 10000108 10000240 10000233 10000246 10000197 10000109 10000108 10000238 10000111 10000236 10000230 10000200 10000177 10000232 10000109 10000188 10000234 10000217 10000143 10000168 10000109 10000235 10000237 10000141 10000160 10000109 10000220 10000199 10000146 10000151 10000221 10000216 10000214
min_time_thread_terminate_total=10000108
max_time_thread_terminate_total=10000254
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 4 4 5 4 4 4 4 5 4 5 5 4 5 4 4 4 3 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 5 5 4 5 4 4 4 4 4 4 4 5 5 5 4 5 5 4 4 4 4 5 5 4 4 4 4 4 4 4 4 4 4 4 4 5 5 5 4 5 5 4 4 4 4 5 5 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=100017303929496
threads_final_size=10001609
final_keysum=100017303929496
final_size=10001609
validate_result=success
Validation OK.
Structural validation OK.
total_find=59252689
total_rq=0
total_inserts=3290783
total_deletes=3289220
total_updates=6580003
total_queries=59252689
total_ops=65832692
find_throughput=5925268
rq_throughput=0
update_throughput=658000
query_throughput=5925268
total_throughput=6583269
total find : 59252689
total rq : 0
total inserts : 3290783
total deletes : 3289220
total updates : 6580003
total queries : 59252689
total ops : 65832692
find throughput : 5925268
rq throughput : 0
update throughput : 658000
query throughput : 5925268
total throughput : 6583269
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=28.6909
PAPI_L3_TCM=8.57427
PAPI_TOT_CYC=40426.2
PAPI_TOT_INS=5714.53
garbage=296286547559838
total_execution_walltime=40.836s
REALTIME_MAIN_END_PERF_FORMAT=6994451.589981651
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 70365006
[slow htm]
[fallback]
total fallback commit : 11000400
seconds global lock is held : 2.07785
[time_cmd_output] time_elapsed_sec=41.08, faults_major=0, faults_minor=429082, mem_maxresident_kb=1713844, user_cputime=3844.67, sys_cputime=11.50, percent_cpu=9385%
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=2