__step=000115
__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=data000115.txt
__path_data=data/data000115.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995638.395209846
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.017s
prefilling_size=618720 prefilling_elapsed_millis=1.001s
prefilling_size=654594 prefilling_elapsed_millis=1.101s
prefilling_size=725378 prefilling_elapsed_millis=1.201s
prefilling_size=796943 prefilling_elapsed_millis=1.301s
prefilling_size=869164 prefilling_elapsed_millis=1.402s
prefilling_size=942207 prefilling_elapsed_millis=1.502s
prefilling_size=1008364 prefilling_elapsed_millis=1.602s
prefilling_size=1059461 prefilling_elapsed_millis=1.702s
prefilling_size=1101038 prefilling_elapsed_millis=1.802s
prefilling_size=1141739 prefilling_elapsed_millis=1.903s
prefilling_size=1174115 prefilling_elapsed_millis=2.003s
prefilling_size=1199926 prefilling_elapsed_millis=2.103s
prefilling_size=1226355 prefilling_elapsed_millis=2.203s
prefilling_size=1251773 prefilling_elapsed_millis=2.303s
prefilling_size=1285503 prefilling_elapsed_millis=2.403s
prefilling_size=1345290 prefilling_elapsed_millis=2.504s
prefilling_size=1410033 prefilling_elapsed_millis=2.604s
prefilling_size=1474427 prefilling_elapsed_millis=2.704s
prefilling_size=1537273 prefilling_elapsed_millis=2.804s
prefilling_size=1600197 prefilling_elapsed_millis=2.904s
prefilling_size=1663399 prefilling_elapsed_millis=3.005s
prefilling_size=1727166 prefilling_elapsed_millis=3.105s
prefilling_size=1790622 prefilling_elapsed_millis=3.205s
prefilling_size=1853730 prefilling_elapsed_millis=3.305s
prefilling_size=1917163 prefilling_elapsed_millis=3.405s
prefilling_size=1977735 prefilling_elapsed_millis=3.506s
prefilling_size=2029359 prefilling_elapsed_millis=3.606s
prefilling_size=2073997 prefilling_elapsed_millis=3.706s
prefilling_size=2113685 prefilling_elapsed_millis=3.806s
prefilling_size=2151153 prefilling_elapsed_millis=3.906s
prefilling_size=2189276 prefilling_elapsed_millis=4.007s
prefilling_size=2226682 prefilling_elapsed_millis=4.107s
prefilling_size=2265877 prefilling_elapsed_millis=4.207s
prefilling_size=2299488 prefilling_elapsed_millis=4.307s
prefilling_size=2331324 prefilling_elapsed_millis=4.407s
prefilling_size=2353366 prefilling_elapsed_millis=4.508s
prefilling_size=2378235 prefilling_elapsed_millis=4.608s
prefilling_size=2403132 prefilling_elapsed_millis=4.708s
prefilling_size=2424307 prefilling_elapsed_millis=4.808s
prefilling_size=2448886 prefilling_elapsed_millis=4.908s
prefilling_size=2475673 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7524270 keys with 96 threads...
tid=0 thread_prefill_amount=27079 percent_done_estimate=34.5 elapsed_s=5 est_remaining_s=22 / 27
tid=0 thread_prefill_amount=29162 percent_done_estimate=37.2 elapsed_s=5 est_remaining_s=20 / 26
tid=0 thread_prefill_amount=31245 percent_done_estimate=39.9 elapsed_s=6 est_remaining_s=19 / 24
tid=0 thread_prefill_amount=33328 percent_done_estimate=42.5 elapsed_s=6 est_remaining_s=18 / 23
tid=0 thread_prefill_amount=35411 percent_done_estimate=45.2 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.5 elapsed_s=7 est_remaining_s=15 / 21
tid=0 thread_prefill_amount=41660 percent_done_estimate=53.2 elapsed_s=7 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=43743 percent_done_estimate=55.8 elapsed_s=7 est_remaining_s=13 / 20
tid=0 thread_prefill_amount=45826 percent_done_estimate=58.5 elapsed_s=8 est_remaining_s=13 / 20
tid=0 thread_prefill_amount=47909 percent_done_estimate=61.1 elapsed_s=8 est_remaining_s=12 / 20
tid=0 thread_prefill_amount=49992 percent_done_estimate=63.8 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=10 / 19
tid=0 thread_prefill_amount=54158 percent_done_estimate=69.1 elapsed_s=10 est_remaining_s=10 / 20
tid=0 thread_prefill_amount=56241 percent_done_estimate=71.8 elapsed_s=10 est_remaining_s=9 / 20
tid=0 thread_prefill_amount=58324 percent_done_estimate=74.4 elapsed_s=11 est_remaining_s=8 / 19
tid=0 thread_prefill_amount=60407 percent_done_estimate=77.1 elapsed_s=11 est_remaining_s=7 / 19
tid=0 thread_prefill_amount=62490 percent_done_estimate=79.7 elapsed_s=11 est_remaining_s=7 / 18
tid=0 thread_prefill_amount=64573 percent_done_estimate=82.4 elapsed_s=12 est_remaining_s=6 / 17
tid=0 thread_prefill_amount=66656 percent_done_estimate=85.0 elapsed_s=12 est_remaining_s=5 / 17
tid=0 thread_prefill_amount=68739 percent_done_estimate=87.7 elapsed_s=12 est_remaining_s=4 / 16
tid=0 thread_prefill_amount=70822 percent_done_estimate=90.4 elapsed_s=13 est_remaining_s=3 / 16
tid=0 thread_prefill_amount=72905 percent_done_estimate=93.0 elapsed_s=13 est_remaining_s=2 / 15
tid=0 thread_prefill_amount=74988 percent_done_estimate=95.7 elapsed_s=13 est_remaining_s=1 / 15
tid=0 thread_prefill_amount=77071 percent_done_estimate=98.3 elapsed_s=14 est_remaining_s=1 / 14
tid=0 thread_prefill_amount=79154 percent_done_estimate=101.0 elapsed_s=14 est_remaining_s=-0 / 14
tid=0 thread_prefill_amount=81237 percent_done_estimate=103.6 elapsed_s=15 est_remaining_s=-1 / 14
tid=0 thread_prefill_amount=83320 percent_done_estimate=106.3 elapsed_s=15 est_remaining_s=-2 / 13
tid=0 thread_prefill_amount=85403 percent_done_estimate=109.0 elapsed_s=16 est_remaining_s=-3 / 13
tid=0 thread_prefill_amount=87486 percent_done_estimate=111.6 elapsed_s=16 est_remaining_s=-4 / 12
tid=0 thread_prefill_amount=89569 percent_done_estimate=114.3 elapsed_s=16 est_remaining_s=-5 / 12
tid=0 thread_prefill_amount=91652 percent_done_estimate=116.9 elapsed_s=17 est_remaining_s=-5 / 11
tid=0 thread_prefill_amount=93735 percent_done_estimate=119.6 elapsed_s=17 est_remaining_s=-6 / 11
tid=0 thread_prefill_amount=95818 percent_done_estimate=122.3 elapsed_s=17 est_remaining_s=-7 / 10
tid=0 thread_prefill_amount=97901 percent_done_estimate=124.9 elapsed_s=18 est_remaining_s=-8 / 10
tid=0 thread_prefill_amount=99984 percent_done_estimate=127.6 elapsed_s=18 est_remaining_s=-9 / 9
tid=0 thread_prefill_amount=102067 percent_done_estimate=130.2 elapsed_s=18 est_remaining_s=-10 / 9
tid=0 thread_prefill_amount=104150 percent_done_estimate=132.9 elapsed_s=19 est_remaining_s=-10 / 8
timing_elapsed 13.9301s
prefilling_size=10000000 prefilling_elapsed_millis=13.963s
finished prefilling to size 10000000 for expected size 10000000 keysum=99979767992558, performing 16913204 updates; total_prefilling_elapsed_ms=13963 ms)
pref_size=10000000
pref_millis=13963
prefill_elapsed_ms=13963
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6995657.668163252
REALTIME_END_PERF_FORMAT=6995667.668243833

###############################################################################
################################## 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 458 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 62 458 3376 25445 198793 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1567965
tree_stats_numNodesAtDepth=1 1 8 62 458 3376 25445 198793 1567965
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9999791
tree_stats_avgDegreeAtDepth=1 8 7.75 7.3871 7.37118 7.53703 7.81265 7.88743 6.37756

tree_stats_height=9
tree_stats_numInternals=228144
tree_stats_numLeaves=1567965
tree_stats_numNodes=1796109
tree_stats_numKeys=9999791

tree_stats_avgDegreeInternal=7.8727
tree_stats_avgDegreeLeaves=6.37756
tree_stats_avgDegree=6.56747
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=296159
first_thread_announced_epoch_by_thread=6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616 6616
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=139785 139087 141098 138639 140208 139469 139971 140773 141223 141631 140901 139174 140762 139946 140691 140823 141130 139765 140043 139907 140560 140746 140250 138566 139843 139307 141035 140877 141289 138807 139204 140454 140736 141355 139855 138928 139145 140064 141518 140663 140114 139818 139238 138938 141265 140504 140099 137868 140691 140924 142391 140990 141394 140339 140773 140182 142646 143290 141459 140059 141189 140970 141759 141539 141148 140417 140169 138611 141715 142168 141361 140177 140576 140463 141919 142095 141068 140285 141444 140513 141728 143112 142344 139328 141563 141671 142702 142284 141891 140306 140184 140792 142486 142935 141697 141063
sum_num_inserts_total=13506882
sum_num_deletes_by_thread=139548 140030 140222 139116 139528 139384 140133 139957 141628 141964 140621 139516 139972 141047 140807 141119 141260 140144 139657 139720 141011 140749 140502 139404 139303 139412 140945 140119 140184 138838 139318 139951 140909 141170 140036 139113 139598 140372 140441 141038 139756 139660 138368 138465 140271 140271 141106 138355 141486 141063 142256 140253 141331 141169 141011 141412 141760 142343 141396 140277 141008 141596 141169 141557 142007 140026 139160 138583 141345 141590 140385 141156 141259 140729 142484 141675 141197 140064 141025 141457 142836 143030 142080 139004 140721 141202 142758 142673 141362 140194 140405 139942 142958 142450 141563 141230
sum_num_deletes_total=13503675
sum_num_searches_by_thread=2508115 2518101 2533222 2501194 2527954 2503946 2517102 2524115 2547248 2549675 2526533 2509018 2520424 2525671 2532439 2538282 2538425 2530791 2508563 2508207 2540832 2538661 2520917 2500662 2506217 2517591 2532385 2525356 2530033 2499342 2510846 2520398 2544815 2547982 2527700 2500390 2515954 2527579 2540312 2533040 2521064 2515096 2501220 2505598 2538664 2531046 2522915 2498479 2528585 2535075 2559383 2540252 2538918 2532381 2534284 2533331 2562636 2570169 2548160 2522278 2536447 2545129 2555563 2550243 2546715 2523594 2515104 2494472 2553631 2556699 2540192 2524685 2533254 2529934 2558383 2551668 2537022 2522361 2536374 2534733 2568957 2576783 2560600 2509827 2547723 2554058 2567858 2562000 2548121 2524944 2520661 2529978 2565178 2568283 2548111 2537126
sum_num_searches_total=243125982
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2787448 2797218 2814542 2778949 2807690 2782799 2797206 2804845 2830099 2833270 2808055 2787708 2801158 2806664 2813937 2820224 2820815 2810700 2788263 2787834 2822403 2820156 2801669 2778632 2785363 2796310 2814365 2806352 2811506 2776987 2789368 2800803 2826460 2830507 2807591 2778431 2794697 2808015 2822271 2814741 2800934 2794574 2778826 2783001 2820200 2811821 2804120 2774702 2810762 2817062 2844030 2821495 2821643 2813889 2816068 2814925 2847042 2855802 2831015 2802614 2818644 2827695 2838491 2833339 2829870 2804037 2794433 2771666 2836691 2840457 2821938 2806018 2815089 2811126 2842786 2835438 2819287 2802710 2818843 2816703 2853521 2862925 2845024 2788159 2830007 2836931 2853318 2846957 2831374 2805444 2801250 2810712 2850622 2853668 2831371 2819419
average_num_operations_total=2813922
stdev_num_operations_total=2150
sum_num_operations_total=270136539
min_num_operations_total=2771666
max_num_operations_total=2862925
first_time_thread_terminate_by_thread=10000159 10000097 10000097 10000095 10000094 10000093 10000093 10000093 10000097 10000095 10000093 10000095 10000096 10000094 10000093 10000093 10000093 10000092 10000094 10000093 10000097 10000097 10000094 10000095 10000097 10000097 10000097 10000093 10000094 10000093 10000095 10000095 10000097 10000095 10000093 10000095 10000093 10000094 10000093 10000095 10000093 10000092 10000094 10000093 10000097 10000097 10000094 10000095 10000096 10000095 10000096 10000095 10000096 10000095 10000096 10000095 10000096 10000094 10000095 10000095 10000094 10000094 10000094 10000096 10000095 10000095 10000094 10000094 10000096 10000096 10000096 10000096 10000096 10000095 10000096 10000095 10000096 10000095 10000096 10000095 10000096 10000094 10000095 10000095 10000094 10000094 10000094 10000096 10000095 10000094 10000096 10000095 10000096 10000096 10000096 10000096
min_time_thread_terminate_total=10000092
max_time_thread_terminate_total=10000159
first_time_thread_start_by_thread=9 5 5 5 5 4 4 5 4 4 5 4 5 4 4 5 4 4 4 5 5 5 5 4 4 5 5 5 5 4 4 5 4 4 5 4 5 4 4 5 4 4 4 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 5 5 5 5 5 5 5 5 5 5 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=100009461753157
threads_final_size=9999791
final_keysum=100009461753157
final_size=9999791
validate_result=success
Validation OK.
Structural validation OK.

total_find=243125982
total_rq=0
total_inserts=13506882
total_deletes=13503675
total_updates=27010557
total_queries=243125982
total_ops=270136539
find_throughput=24312598
rq_throughput=0
update_throughput=2701055
query_throughput=24312598
total_throughput=27013653


total find                    : 243125982
total rq                      : 0
total inserts                 : 13506882
total deletes                 : 13503675
total updates                 : 27010557
total queries                 : 243125982
total ops                     : 270136539
find throughput               : 24312598
rq throughput                 : 0
update throughput             : 2701055
query throughput              : 24312598
total throughput              : 27013653

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=17.2552
PAPI_L3_TCM=6.11319
PAPI_TOT_CYC=9863.4
PAPI_TOT_INS=7072.85
garbage=1190367867554998
total_execution_walltime=29.413s
REALTIME_MAIN_END_PERF_FORMAT=6995667.809905421
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 285443261
[slow htm]
[fallback]
total fallback commit         : 1606483
seconds global lock is held   : 23.6381
[time_cmd_output] time_elapsed_sec=29.74, faults_major=0, faults_minor=810817, mem_maxresident_kb=3040660, user_cputime=2783.70, sys_cputime=7.94, percent_cpu=9385%
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=1