__step=000113
__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 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=data000113.txt
__path_data=data/data000113.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995580.395783285
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=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=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


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.019s
prefilling_size=654274 prefilling_elapsed_millis=1.001s
prefilling_size=724989 prefilling_elapsed_millis=1.101s
prefilling_size=793042 prefilling_elapsed_millis=1.201s
prefilling_size=861967 prefilling_elapsed_millis=1.301s
prefilling_size=929196 prefilling_elapsed_millis=1.402s
prefilling_size=998087 prefilling_elapsed_millis=1.502s
prefilling_size=1055311 prefilling_elapsed_millis=1.602s
prefilling_size=1106572 prefilling_elapsed_millis=1.702s
prefilling_size=1158466 prefilling_elapsed_millis=1.802s
prefilling_size=1203859 prefilling_elapsed_millis=1.903s
prefilling_size=1219362 prefilling_elapsed_millis=2.003s
prefilling_size=1230920 prefilling_elapsed_millis=2.103s
prefilling_size=1277616 prefilling_elapsed_millis=2.203s
prefilling_size=1340932 prefilling_elapsed_millis=2.303s
prefilling_size=1404093 prefilling_elapsed_millis=2.404s
prefilling_size=1467541 prefilling_elapsed_millis=2.504s
prefilling_size=1531100 prefilling_elapsed_millis=2.604s
prefilling_size=1593978 prefilling_elapsed_millis=2.704s
prefilling_size=1657079 prefilling_elapsed_millis=2.804s
prefilling_size=1720380 prefilling_elapsed_millis=2.905s
prefilling_size=1782348 prefilling_elapsed_millis=3.005s
prefilling_size=1844613 prefilling_elapsed_millis=3.105s
prefilling_size=1906972 prefilling_elapsed_millis=3.205s
prefilling_size=1969041 prefilling_elapsed_millis=3.305s
prefilling_size=2024841 prefilling_elapsed_millis=3.405s
prefilling_size=2073660 prefilling_elapsed_millis=3.506s
prefilling_size=2121976 prefilling_elapsed_millis=3.606s
prefilling_size=2170069 prefilling_elapsed_millis=3.706s
prefilling_size=2218364 prefilling_elapsed_millis=3.806s
prefilling_size=2264955 prefilling_elapsed_millis=3.906s
prefilling_size=2312024 prefilling_elapsed_millis=4.007s
prefilling_size=2360519 prefilling_elapsed_millis=4.107s
prefilling_size=2397200 prefilling_elapsed_millis=4.207s
prefilling_size=2409284 prefilling_elapsed_millis=4.307s
prefilling_size=2418604 prefilling_elapsed_millis=4.407s
prefilling_size=2431465 prefilling_elapsed_millis=4.508s
prefilling_size=2448834 prefilling_elapsed_millis=4.608s
prefilling_size=2485129 prefilling_elapsed_millis=4.708s
prefilling_size=2539792 prefilling_elapsed_millis=4.808s
prefilling_size=2599272 prefilling_elapsed_millis=4.908s
prefilling_size=2657870 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7342020 keys with 48 threads...
tid=0 thread_prefill_amount=58324 percent_done_estimate=38.1 elapsed_s=5 est_remaining_s=19 / 24
tid=0 thread_prefill_amount=62490 percent_done_estimate=40.9 elapsed_s=5 est_remaining_s=18 / 23
tid=0 thread_prefill_amount=66656 percent_done_estimate=43.6 elapsed_s=6 est_remaining_s=16 / 22
tid=0 thread_prefill_amount=70822 percent_done_estimate=46.3 elapsed_s=6 est_remaining_s=15 / 21
tid=0 thread_prefill_amount=74988 percent_done_estimate=49.0 elapsed_s=6 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=79154 percent_done_estimate=51.7 elapsed_s=6 est_remaining_s=14 / 20
tid=0 thread_prefill_amount=83320 percent_done_estimate=54.5 elapsed_s=7 est_remaining_s=13 / 19
tid=0 thread_prefill_amount=87486 percent_done_estimate=57.2 elapsed_s=7 est_remaining_s=12 / 19
tid=0 thread_prefill_amount=91652 percent_done_estimate=59.9 elapsed_s=8 est_remaining_s=11 / 19
tid=0 thread_prefill_amount=95818 percent_done_estimate=62.6 elapsed_s=8 est_remaining_s=11 / 18
tid=0 thread_prefill_amount=99984 percent_done_estimate=65.4 elapsed_s=8 est_remaining_s=10 / 18
tid=0 thread_prefill_amount=104150 percent_done_estimate=68.1 elapsed_s=9 est_remaining_s=9 / 18
tid=0 thread_prefill_amount=108316 percent_done_estimate=70.8 elapsed_s=9 est_remaining_s=8 / 17
tid=0 thread_prefill_amount=112482 percent_done_estimate=73.5 elapsed_s=10 est_remaining_s=8 / 18
tid=0 thread_prefill_amount=116648 percent_done_estimate=76.3 elapsed_s=10 est_remaining_s=7 / 17
tid=0 thread_prefill_amount=120814 percent_done_estimate=79.0 elapsed_s=10 est_remaining_s=6 / 17
tid=0 thread_prefill_amount=124980 percent_done_estimate=81.7 elapsed_s=11 est_remaining_s=5 / 16
tid=0 thread_prefill_amount=129146 percent_done_estimate=84.4 elapsed_s=11 est_remaining_s=5 / 16
tid=0 thread_prefill_amount=133312 percent_done_estimate=87.2 elapsed_s=11 est_remaining_s=4 / 15
tid=0 thread_prefill_amount=137478 percent_done_estimate=89.9 elapsed_s=12 est_remaining_s=3 / 15
tid=0 thread_prefill_amount=141644 percent_done_estimate=92.6 elapsed_s=12 est_remaining_s=2 / 14
tid=0 thread_prefill_amount=145810 percent_done_estimate=95.3 elapsed_s=12 est_remaining_s=1 / 14
tid=0 thread_prefill_amount=149976 percent_done_estimate=98.0 elapsed_s=13 est_remaining_s=1 / 13
tid=0 thread_prefill_amount=154142 percent_done_estimate=100.8 elapsed_s=13 est_remaining_s=-0 / 13
tid=0 thread_prefill_amount=158308 percent_done_estimate=103.5 elapsed_s=13 est_remaining_s=-1 / 12
tid=0 thread_prefill_amount=162474 percent_done_estimate=106.2 elapsed_s=14 est_remaining_s=-2 / 13
tid=0 thread_prefill_amount=166640 percent_done_estimate=108.9 elapsed_s=15 est_remaining_s=-3 / 12
tid=0 thread_prefill_amount=170806 percent_done_estimate=111.7 elapsed_s=15 est_remaining_s=-4 / 12
tid=0 thread_prefill_amount=174972 percent_done_estimate=114.4 elapsed_s=15 est_remaining_s=-4 / 11
tid=0 thread_prefill_amount=179138 percent_done_estimate=117.1 elapsed_s=16 est_remaining_s=-5 / 11
tid=0 thread_prefill_amount=183304 percent_done_estimate=119.8 elapsed_s=16 est_remaining_s=-6 / 10
tid=0 thread_prefill_amount=187470 percent_done_estimate=122.6 elapsed_s=16 est_remaining_s=-7 / 10
tid=0 thread_prefill_amount=191636 percent_done_estimate=125.3 elapsed_s=17 est_remaining_s=-8 / 9
tid=0 thread_prefill_amount=195802 percent_done_estimate=128.0 elapsed_s=17 est_remaining_s=-8 / 9
tid=0 thread_prefill_amount=199968 percent_done_estimate=130.7 elapsed_s=17 est_remaining_s=-9 / 8
tid=0 thread_prefill_amount=204134 percent_done_estimate=133.5 elapsed_s=18 est_remaining_s=-10 / 8
tid=0 thread_prefill_amount=208300 percent_done_estimate=136.2 elapsed_s=18 est_remaining_s=-11 / 7
timing_elapsed 13.1417s
prefilling_size=10000000 prefilling_elapsed_millis=13.173s
finished prefilling to size 10000000 for expected size 10000000 keysum=100004869697119, performing 17199355 updates; total_prefilling_elapsed_ms=13173 ms)
pref_size=10000000
pref_millis=13173
prefill_elapsed_ms=13173
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6995598.882275384
REALTIME_END_PERF_FORMAT=6995608.882355234

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

tree_stats_computeWalltime=0.014s

tree_stats_numInternalsAtDepth=1 1 9 60 455 3287 24870 193356 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1526669
tree_stats_numNodesAtDepth=1 1 9 60 455 3287 24870 193356 1526669
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9995706
tree_stats_avgDegreeAtDepth=1 9 6.66667 7.58333 7.22418 7.56617 7.77467 7.89564 6.5474

tree_stats_height=9
tree_stats_numInternals=222039
tree_stats_numLeaves=1526669
tree_stats_numNodes=1748708
tree_stats_numKeys=9995706

tree_stats_avgDegreeInternal=7.87568
tree_stats_avgDegreeLeaves=6.5474
tree_stats_avgDegree=6.71605
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=456143
first_thread_announced_epoch_by_thread=20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854 20854
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=213517 213950 215210 214184 214021 211865 212382 213489 216241 215625 213143 212426 214015 213988 216184 214183 214163 212727 213206 210722 215052 213423 213562 212246 213355 213399 215447 213493 214290 211927 214031 213812 215726 215983 213742 213062 214046 214891 215861 213200 213718 213276 212107 209873 214560 214354 213197 213042
sum_num_inserts_total=10259916
sum_num_deletes_by_thread=212636 213479 215504 213332 214212 212038 212311 213667 215690 215787 214246 211034 214423 215212 215570 215973 214960 212394 212193 210973 215329 212770 213497 211484 212461 213153 214519 214459 213992 212997 213316 214174 215926 216214 214272 211934 213991 213453 215510 213714 214060 212973 211989 210085 213783 215870 213579 211978
sum_num_deletes_total=10257116
sum_num_searches_by_thread=3829896 3836905 3867540 3850295 3855686 3804108 3824332 3845420 3874585 3879925 3848824 3803340 3844047 3859438 3878970 3870812 3864564 3835583 3829228 3801387 3866121 3842656 3840395 3815639 3826936 3841144 3869368 3848848 3863686 3829629 3843808 3855780 3890258 3888568 3841257 3823285 3849876 3852700 3880144 3844845 3860638 3842142 3828995 3772896 3860938 3862770 3849020 3816382
sum_num_searches_total=184613609
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4256049 4264334 4298254 4277811 4283919 4228011 4249025 4272576 4306516 4311337 4276213 4226800 4272485 4288638 4310724 4300968 4293687 4260704 4254627 4223082 4296502 4268849 4267454 4239369 4252752 4267696 4299334 4276800 4291968 4254553 4271155 4283766 4321910 4320765 4269271 4248281 4277913 4281044 4311515 4271759 4288416 4268391 4253091 4192854 4289281 4292994 4275796 4241402
average_num_operations_total=4273555
stdev_num_operations_total=3902
sum_num_operations_total=205130641
min_num_operations_total=4192854
max_num_operations_total=4321910
first_time_thread_terminate_by_thread=10000131 10000079 10000080 10000079 10000081 10000080 10000079 10000079 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000079 10000079 10000080 10000080 10000080 10000080 10000079 10000079 10000079 10000079 10000080 10000079 10000081 10000079 10000080 10000079 10000080 10000080 10000080 10000080 10000079 10000080 10000080 10000080 10000079 10000080 10000080 10000079 10000080 10000080 10000079 10000080
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000131
first_time_thread_start_by_thread=11 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 4 5 5 5 4 5 5 5 4 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 4 5 5 5 4 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=99967252582906
threads_final_size=9995706
final_keysum=99967252582906
final_size=9995706
validate_result=success
Validation OK.
Structural validation OK.

total_find=184613609
total_rq=0
total_inserts=10259916
total_deletes=10257116
total_updates=20517032
total_queries=184613609
total_ops=205130641
find_throughput=18461360
rq_throughput=0
update_throughput=2051703
query_throughput=18461360
total_throughput=20513064


total find                    : 184613609
total rq                      : 0
total inserts                 : 10259916
total deletes                 : 10257116
total updates                 : 20517032
total queries                 : 184613609
total ops                     : 205130641
find throughput               : 18461360
rq throughput                 : 0
update throughput             : 2051703
query throughput              : 18461360
total throughput              : 20513064

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=17.0263
PAPI_L3_TCM=5.77727
PAPI_TOT_CYC=6494.74
PAPI_TOT_INS=3833.67
garbage=923848068948345
total_execution_walltime=28.628s
REALTIME_MAIN_END_PERF_FORMAT=6995609.48693737
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 220798031
[slow htm]
[fallback]
total fallback commit         : 1531966
seconds global lock is held   : 20.9815
[time_cmd_output] time_elapsed_sec=28.95, faults_major=0, faults_minor=791934, mem_maxresident_kb=2988012, user_cputime=1360.02, sys_cputime=4.76, percent_cpu=4713%
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