__step=000116
__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.hytm1 -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=data000116.txt
__path_data=data/data000116.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995668.186581511
binary=./brown_abtree_tm_auto.debra.hytm1
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


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.04s
prefilling_size=615602 prefilling_elapsed_millis=1.001s
prefilling_size=658016 prefilling_elapsed_millis=1.101s
prefilling_size=731862 prefilling_elapsed_millis=1.201s
prefilling_size=805492 prefilling_elapsed_millis=1.301s
prefilling_size=879130 prefilling_elapsed_millis=1.402s
prefilling_size=951178 prefilling_elapsed_millis=1.502s
prefilling_size=1017655 prefilling_elapsed_millis=1.602s
prefilling_size=1064593 prefilling_elapsed_millis=1.702s
prefilling_size=1105570 prefilling_elapsed_millis=1.802s
prefilling_size=1146613 prefilling_elapsed_millis=1.903s
prefilling_size=1180147 prefilling_elapsed_millis=2.003s
prefilling_size=1204093 prefilling_elapsed_millis=2.103s
prefilling_size=1222689 prefilling_elapsed_millis=2.203s
prefilling_size=1246178 prefilling_elapsed_millis=2.303s
prefilling_size=1285269 prefilling_elapsed_millis=2.404s
prefilling_size=1349705 prefilling_elapsed_millis=2.504s
prefilling_size=1415568 prefilling_elapsed_millis=2.604s
prefilling_size=1479154 prefilling_elapsed_millis=2.704s
prefilling_size=1543432 prefilling_elapsed_millis=2.804s
prefilling_size=1607000 prefilling_elapsed_millis=2.905s
prefilling_size=1670425 prefilling_elapsed_millis=3.005s
prefilling_size=1733513 prefilling_elapsed_millis=3.105s
prefilling_size=1797172 prefilling_elapsed_millis=3.205s
prefilling_size=1860473 prefilling_elapsed_millis=3.305s
prefilling_size=1923428 prefilling_elapsed_millis=3.406s
prefilling_size=1985690 prefilling_elapsed_millis=3.506s
prefilling_size=2038654 prefilling_elapsed_millis=3.606s
prefilling_size=2080208 prefilling_elapsed_millis=3.706s
prefilling_size=2118455 prefilling_elapsed_millis=3.806s
prefilling_size=2156160 prefilling_elapsed_millis=3.907s
prefilling_size=2194090 prefilling_elapsed_millis=4.007s
prefilling_size=2230864 prefilling_elapsed_millis=4.107s
prefilling_size=2268791 prefilling_elapsed_millis=4.207s
prefilling_size=2305599 prefilling_elapsed_millis=4.307s
prefilling_size=2337291 prefilling_elapsed_millis=4.408s
prefilling_size=2368438 prefilling_elapsed_millis=4.508s
prefilling_size=2388774 prefilling_elapsed_millis=4.608s
prefilling_size=2405541 prefilling_elapsed_millis=4.708s
prefilling_size=2419344 prefilling_elapsed_millis=4.808s
prefilling_size=2438305 prefilling_elapsed_millis=4.909s
prefilling_size=2466022 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7533910 keys with 96 threads...
tid=0 thread_prefill_amount=27079 percent_done_estimate=34.5 elapsed_s=5 est_remaining_s=22 / 28
tid=0 thread_prefill_amount=29162 percent_done_estimate=37.2 elapsed_s=5 est_remaining_s=21 / 26
tid=0 thread_prefill_amount=31245 percent_done_estimate=39.8 elapsed_s=6 est_remaining_s=19 / 25
tid=0 thread_prefill_amount=33328 percent_done_estimate=42.5 elapsed_s=6 est_remaining_s=18 / 24
tid=0 thread_prefill_amount=35411 percent_done_estimate=45.1 elapsed_s=6 est_remaining_s=17 / 23
tid=0 thread_prefill_amount=37494 percent_done_estimate=47.8 elapsed_s=6 est_remaining_s=16 / 22
tid=0 thread_prefill_amount=39577 percent_done_estimate=50.4 elapsed_s=7 est_remaining_s=15 / 22
tid=0 thread_prefill_amount=41660 percent_done_estimate=53.1 elapsed_s=7 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=43743 percent_done_estimate=55.7 elapsed_s=7 est_remaining_s=13 / 21
tid=0 thread_prefill_amount=45826 percent_done_estimate=58.4 elapsed_s=8 est_remaining_s=13 / 21
tid=0 thread_prefill_amount=47909 percent_done_estimate=61.0 elapsed_s=8 est_remaining_s=12 / 20
tid=0 thread_prefill_amount=49992 percent_done_estimate=63.7 elapsed_s=9 est_remaining_s=11 / 20
tid=0 thread_prefill_amount=52075 percent_done_estimate=66.4 elapsed_s=9 est_remaining_s=11 / 20
tid=0 thread_prefill_amount=54158 percent_done_estimate=69.0 elapsed_s=10 est_remaining_s=10 / 20
tid=0 thread_prefill_amount=56241 percent_done_estimate=71.7 elapsed_s=11 est_remaining_s=9 / 20
tid=0 thread_prefill_amount=58324 percent_done_estimate=74.3 elapsed_s=11 est_remaining_s=9 / 19
tid=0 thread_prefill_amount=60407 percent_done_estimate=77.0 elapsed_s=11 est_remaining_s=8 / 19
tid=0 thread_prefill_amount=62490 percent_done_estimate=79.6 elapsed_s=12 est_remaining_s=7 / 18
tid=0 thread_prefill_amount=64573 percent_done_estimate=82.3 elapsed_s=12 est_remaining_s=6 / 18
tid=0 thread_prefill_amount=66656 percent_done_estimate=84.9 elapsed_s=12 est_remaining_s=5 / 17
tid=0 thread_prefill_amount=68739 percent_done_estimate=87.6 elapsed_s=13 est_remaining_s=4 / 17
tid=0 thread_prefill_amount=70822 percent_done_estimate=90.2 elapsed_s=13 est_remaining_s=3 / 16
tid=0 thread_prefill_amount=72905 percent_done_estimate=92.9 elapsed_s=13 est_remaining_s=2 / 15
tid=0 thread_prefill_amount=74988 percent_done_estimate=95.6 elapsed_s=14 est_remaining_s=1 / 15
tid=0 thread_prefill_amount=77071 percent_done_estimate=98.2 elapsed_s=14 est_remaining_s=1 / 15
tid=0 thread_prefill_amount=79154 percent_done_estimate=100.9 elapsed_s=15 est_remaining_s=-0 / 15
tid=0 thread_prefill_amount=81237 percent_done_estimate=103.5 elapsed_s=15 est_remaining_s=-1 / 14
tid=0 thread_prefill_amount=83320 percent_done_estimate=106.2 elapsed_s=16 est_remaining_s=-2 / 13
tid=0 thread_prefill_amount=85403 percent_done_estimate=108.8 elapsed_s=16 est_remaining_s=-3 / 13
tid=0 thread_prefill_amount=87486 percent_done_estimate=111.5 elapsed_s=16 est_remaining_s=-4 / 12
tid=0 thread_prefill_amount=89569 percent_done_estimate=114.1 elapsed_s=16 est_remaining_s=-5 / 12
tid=0 thread_prefill_amount=91652 percent_done_estimate=116.8 elapsed_s=17 est_remaining_s=-5 / 11
tid=0 thread_prefill_amount=93735 percent_done_estimate=119.4 elapsed_s=17 est_remaining_s=-6 / 11
tid=0 thread_prefill_amount=95818 percent_done_estimate=122.1 elapsed_s=17 est_remaining_s=-7 / 10
tid=0 thread_prefill_amount=97901 percent_done_estimate=124.7 elapsed_s=18 est_remaining_s=-8 / 10
tid=0 thread_prefill_amount=99984 percent_done_estimate=127.4 elapsed_s=18 est_remaining_s=-9 / 9
tid=0 thread_prefill_amount=102067 percent_done_estimate=130.1 elapsed_s=18 est_remaining_s=-10 / 9
tid=0 thread_prefill_amount=104150 percent_done_estimate=132.7 elapsed_s=19 est_remaining_s=-10 / 8
timing_elapsed 13.7452s
prefilling_size=10000000 prefilling_elapsed_millis=13.778s
finished prefilling to size 10000000 for expected size 10000000 keysum=100002771947420, performing 16896997 updates; total_prefilling_elapsed_ms=13778 ms)
pref_size=10000000
pref_millis=13778
prefill_elapsed_ms=13778
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6995687.312163145
REALTIME_END_PERF_FORMAT=6995697.312243219

###############################################################################
################################## 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 465 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 65 465 3389 25523 198570 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1567815
tree_stats_numNodesAtDepth=1 1 8 65 465 3389 25523 198570 1567815
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10000303
tree_stats_avgDegreeAtDepth=1 8 8.125 7.15385 7.28817 7.53113 7.78004 7.89553 6.3785

tree_stats_height=9
tree_stats_numInternals=228022
tree_stats_numLeaves=1567815
tree_stats_numNodes=1795837
tree_stats_numKeys=10000303

tree_stats_avgDegreeInternal=7.87572
tree_stats_avgDegreeLeaves=6.3785
tree_stats_avgDegree=6.5686
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=294815
first_thread_announced_epoch_by_thread=6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572 6572
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=138515 138582 139523 140031 139762 138330 138838 140085 140834 141118 139320 138633 140260 139858 140577 141258 140186 139104 138393 138743 139668 141134 139664 137205 138621 139070 140175 139748 139414 137857 138600 140318 141172 141594 138740 138925 139658 139621 140406 140838 140695 139061 138725 138565 139999 140273 138652 138893 140071 141075 141550 141276 140062 140245 140188 140104 141945 142339 140653 139819 140937 141457 141292 142184 140593 140086 139184 140457 140908 141823 140673 139724 139848 141121 141286 141778 141021 140503 140692 140677 142720 142338 141096 139867 140591 140291 141518 142383 141492 139760 139447 139662 141917 142369 141329 141133
sum_num_inserts_total=13462725
sum_num_deletes_by_thread=138012 139400 140063 139676 139985 138421 139063 139507 140736 141000 140423 139279 139191 140628 140592 140669 140251 138363 138645 138720 140445 140520 139197 138649 138451 139557 139758 139698 140301 138382 138757 138697 139900 140782 139757 138671 139222 139426 140184 140921 139927 139215 137999 139508 139469 139573 139120 138298 139978 141075 141939 140606 140198 139954 140600 139760 141327 142167 140599 139899 140647 141383 141145 141174 140591 139152 139373 140016 140696 141376 140100 139668 140149 140812 141836 142165 140974 140779 140400 140467 141870 142557 140314 139457 140581 140874 140808 141610 141400 139715 139629 140401 142317 142707 141281 140424
sum_num_deletes_total=13453958
sum_num_searches_by_thread=2486979 2505163 2523617 2524095 2518080 2490688 2501033 2511443 2532430 2543457 2513765 2507219 2515332 2516951 2531332 2533879 2523335 2503883 2492434 2499066 2518048 2530086 2509885 2482935 2492518 2506425 2514696 2518186 2517756 2493446 2499363 2509611 2528135 2536097 2506151 2500155 2503659 2515342 2529831 2528976 2523858 2506843 2490969 2506104 2512391 2523976 2503573 2483437 2522764 2535938 2551038 2537668 2524467 2517174 2520736 2519926 2554286 2556114 2537098 2518253 2527987 2536323 2540898 2546825 2531820 2508627 2507676 2520413 2548063 2546163 2533398 2519799 2521009 2536821 2549667 2553810 2535047 2526547 2528618 2534945 2555193 2556399 2536857 2513722 2528135 2535771 2544765 2551074 2537141 2519736 2507991 2524459 2557386 2562298 2537027 2531841
sum_num_searches_total=242216346
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2763506 2783145 2803203 2803802 2797827 2767439 2778934 2791035 2814000 2825575 2793508 2785131 2794783 2797437 2812501 2815806 2803772 2781350 2769472 2776529 2798161 2811740 2788746 2758789 2769590 2785052 2794629 2797632 2797471 2769685 2776720 2788626 2809207 2818473 2784648 2777751 2782539 2794389 2810421 2810735 2804480 2785119 2767693 2784177 2791859 2803822 2781345 2760628 2802813 2818088 2834527 2819550 2804727 2797373 2801524 2799790 2837558 2840620 2818350 2797971 2809571 2819163 2823335 2830183 2813004 2787865 2786233 2800886 2829667 2829362 2814171 2799191 2801006 2818754 2832789 2837753 2817042 2807829 2809710 2816089 2839783 2841294 2818267 2793046 2809307 2816936 2827091 2835067 2820033 2799211 2787067 2804522 2841620 2847374 2819637 2813398
average_num_operations_total=2803469
stdev_num_operations_total=2101
sum_num_operations_total=269133029
min_num_operations_total=2758789
max_num_operations_total=2847374
first_time_thread_terminate_by_thread=10000138 10000137 10000138 10000136 10000138 10000136 10000137 10000135 10000137 10000137 10000136 10000136 10000138 10000136 10000138 10000137 10000136 10000138 10000138 10000138 10000136 10000134 10000136 10000134 10000136 10000136 10000138 10000136 10000138 10000136 10000137 10000135 10000137 10000137 10000136 10000136 10000138 10000136 10000138 10000137 10000136 10000138 10000138 10000138 10000136 10000134 10000136 10000138 10000134 10000135 10000136 10000135 10000138 10000134 10000134 10000136 10000136 10000134 10000136 10000134 10000302 10000135 10000134 10000134 10000135 10000136 10000137 10000135 10000134 10000278 10000137 10000135 10000134 10000137 10000136 10000135 10000138 10000136 10000134 10000135 10000136 10000136 10000136 10000134 10000134 10000134 10000134 10000134 10000134 10000136 10000133 10000134 10000137 10000135 10000137 10000135
min_time_thread_terminate_total=10000133
max_time_thread_terminate_total=10000302
first_time_thread_start_by_thread=9 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 4 4 5 4 5 4 5 5 5 4 5 5 4 4 4 4 4 4 4 4 4 4 4 5 4 4 5 4 5 4 5 5 5 4 5 5 4 4 4 4 4 4 4 4 4 4 4 5
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100014364364917
threads_final_size=10000303
final_keysum=100014364364917
final_size=10000303
validate_result=success
Validation OK.
Structural validation OK.

total_find=242216346
total_rq=0
total_inserts=13462725
total_deletes=13453958
total_updates=26916683
total_queries=242216346
total_ops=269133029
find_throughput=24221634
rq_throughput=0
update_throughput=2691668
query_throughput=24221634
total_throughput=26913302


total find                    : 242216346
total rq                      : 0
total inserts                 : 13462725
total deletes                 : 13453958
total updates                 : 26916683
total queries                 : 242216346
total ops                     : 269133029
find throughput               : 24221634
rq throughput                 : 0
update throughput             : 2691668
query throughput              : 24221634
total throughput              : 26913302

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=17.2786
PAPI_L3_TCM=6.11868
PAPI_TOT_CYC=9895.61
PAPI_TOT_INS=7126.51
garbage=1211312377913747
total_execution_walltime=29.267s
REALTIME_MAIN_END_PERF_FORMAT=6995697.454783323
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 284437457
[slow htm]
[fallback]
total fallback commit         : 1592570
seconds global lock is held   : 23.6795
[time_cmd_output] time_elapsed_sec=29.58, faults_major=0, faults_minor=810038, mem_maxresident_kb=3036288, user_cputime=2772.35, sys_cputime=8.77, percent_cpu=9401%
timeout=false
algorithm=brown_abtree_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2