__step=000114
__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=data000114.txt
__path_data=data/data000114.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995609.417669937
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.006s
prefilling_size=643218 prefilling_elapsed_millis=1.001s
prefilling_size=712223 prefilling_elapsed_millis=1.101s
prefilling_size=779717 prefilling_elapsed_millis=1.201s
prefilling_size=846150 prefilling_elapsed_millis=1.301s
prefilling_size=914548 prefilling_elapsed_millis=1.402s
prefilling_size=980704 prefilling_elapsed_millis=1.502s
prefilling_size=1040902 prefilling_elapsed_millis=1.602s
prefilling_size=1091224 prefilling_elapsed_millis=1.702s
prefilling_size=1141169 prefilling_elapsed_millis=1.802s
prefilling_size=1190403 prefilling_elapsed_millis=1.903s
prefilling_size=1210389 prefilling_elapsed_millis=2.003s
prefilling_size=1229391 prefilling_elapsed_millis=2.103s
prefilling_size=1260788 prefilling_elapsed_millis=2.203s
prefilling_size=1317026 prefilling_elapsed_millis=2.303s
prefilling_size=1379336 prefilling_elapsed_millis=2.404s
prefilling_size=1442112 prefilling_elapsed_millis=2.504s
prefilling_size=1504539 prefilling_elapsed_millis=2.604s
prefilling_size=1567543 prefilling_elapsed_millis=2.704s
prefilling_size=1630061 prefilling_elapsed_millis=2.804s
prefilling_size=1692589 prefilling_elapsed_millis=2.905s
prefilling_size=1754334 prefilling_elapsed_millis=3.005s
prefilling_size=1815417 prefilling_elapsed_millis=3.105s
prefilling_size=1876961 prefilling_elapsed_millis=3.205s
prefilling_size=1937720 prefilling_elapsed_millis=3.305s
prefilling_size=1997764 prefilling_elapsed_millis=3.406s
prefilling_size=2049765 prefilling_elapsed_millis=3.506s
prefilling_size=2097099 prefilling_elapsed_millis=3.606s
prefilling_size=2144169 prefilling_elapsed_millis=3.706s
prefilling_size=2190752 prefilling_elapsed_millis=3.806s
prefilling_size=2237233 prefilling_elapsed_millis=3.907s
prefilling_size=2284710 prefilling_elapsed_millis=4.007s
prefilling_size=2332268 prefilling_elapsed_millis=4.107s
prefilling_size=2372390 prefilling_elapsed_millis=4.207s
prefilling_size=2392617 prefilling_elapsed_millis=4.307s
prefilling_size=2408708 prefilling_elapsed_millis=4.408s
prefilling_size=2421307 prefilling_elapsed_millis=4.508s
prefilling_size=2441281 prefilling_elapsed_millis=4.608s
prefilling_size=2467605 prefilling_elapsed_millis=4.708s
prefilling_size=2504511 prefilling_elapsed_millis=4.808s
prefilling_size=2564560 prefilling_elapsed_millis=4.908s
prefilling_size=2624249 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7375665 keys with 48 threads...
tid=0 thread_prefill_amount=54158 percent_done_estimate=35.2 elapsed_s=5 est_remaining_s=21 / 27
tid=0 thread_prefill_amount=58324 percent_done_estimate=38.0 elapsed_s=5 est_remaining_s=20 / 25
tid=0 thread_prefill_amount=62490 percent_done_estimate=40.7 elapsed_s=6 est_remaining_s=18 / 24
tid=0 thread_prefill_amount=66656 percent_done_estimate=43.4 elapsed_s=6 est_remaining_s=17 / 23
tid=0 thread_prefill_amount=70822 percent_done_estimate=46.1 elapsed_s=6 est_remaining_s=16 / 22
tid=0 thread_prefill_amount=74988 percent_done_estimate=48.8 elapsed_s=6 est_remaining_s=15 / 22
tid=0 thread_prefill_amount=79154 percent_done_estimate=51.5 elapsed_s=7 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=83320 percent_done_estimate=54.2 elapsed_s=7 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=87486 percent_done_estimate=56.9 elapsed_s=8 est_remaining_s=13 / 20
tid=0 thread_prefill_amount=91652 percent_done_estimate=59.6 elapsed_s=8 est_remaining_s=12 / 20
tid=0 thread_prefill_amount=95818 percent_done_estimate=62.4 elapsed_s=8 est_remaining_s=11 / 19
tid=0 thread_prefill_amount=99984 percent_done_estimate=65.1 elapsed_s=9 est_remaining_s=10 / 19
tid=0 thread_prefill_amount=104150 percent_done_estimate=67.8 elapsed_s=9 est_remaining_s=10 / 19
tid=0 thread_prefill_amount=108316 percent_done_estimate=70.5 elapsed_s=10 est_remaining_s=9 / 19
tid=0 thread_prefill_amount=112482 percent_done_estimate=73.2 elapsed_s=10 est_remaining_s=8 / 19
tid=0 thread_prefill_amount=116648 percent_done_estimate=75.9 elapsed_s=10 est_remaining_s=7 / 18
tid=0 thread_prefill_amount=120814 percent_done_estimate=78.6 elapsed_s=11 est_remaining_s=7 / 17
tid=0 thread_prefill_amount=124980 percent_done_estimate=81.3 elapsed_s=11 est_remaining_s=6 / 17
tid=0 thread_prefill_amount=129146 percent_done_estimate=84.0 elapsed_s=11 est_remaining_s=5 / 16
tid=0 thread_prefill_amount=133312 percent_done_estimate=86.8 elapsed_s=12 est_remaining_s=4 / 16
tid=0 thread_prefill_amount=137478 percent_done_estimate=89.5 elapsed_s=12 est_remaining_s=3 / 15
tid=0 thread_prefill_amount=141644 percent_done_estimate=92.2 elapsed_s=12 est_remaining_s=2 / 15
tid=0 thread_prefill_amount=145810 percent_done_estimate=94.9 elapsed_s=13 est_remaining_s=2 / 14
tid=0 thread_prefill_amount=149976 percent_done_estimate=97.6 elapsed_s=13 est_remaining_s=1 / 14
tid=0 thread_prefill_amount=154142 percent_done_estimate=100.3 elapsed_s=13 est_remaining_s=-0 / 13
tid=0 thread_prefill_amount=158308 percent_done_estimate=103.0 elapsed_s=14 est_remaining_s=-1 / 13
tid=0 thread_prefill_amount=162474 percent_done_estimate=105.7 elapsed_s=15 est_remaining_s=-2 / 13
tid=0 thread_prefill_amount=166640 percent_done_estimate=108.4 elapsed_s=15 est_remaining_s=-3 / 12
tid=0 thread_prefill_amount=170806 percent_done_estimate=111.2 elapsed_s=15 est_remaining_s=-3 / 12
tid=0 thread_prefill_amount=174972 percent_done_estimate=113.9 elapsed_s=16 est_remaining_s=-4 / 11
tid=0 thread_prefill_amount=179138 percent_done_estimate=116.6 elapsed_s=16 est_remaining_s=-5 / 11
tid=0 thread_prefill_amount=183304 percent_done_estimate=119.3 elapsed_s=16 est_remaining_s=-6 / 10
tid=0 thread_prefill_amount=187470 percent_done_estimate=122.0 elapsed_s=17 est_remaining_s=-7 / 10
tid=0 thread_prefill_amount=191636 percent_done_estimate=124.7 elapsed_s=17 est_remaining_s=-8 / 9
tid=0 thread_prefill_amount=195802 percent_done_estimate=127.4 elapsed_s=17 est_remaining_s=-8 / 9
tid=0 thread_prefill_amount=199968 percent_done_estimate=130.1 elapsed_s=18 est_remaining_s=-9 / 8
tid=0 thread_prefill_amount=204134 percent_done_estimate=132.8 elapsed_s=18 est_remaining_s=-10 / 8
timing_elapsed 13.1059s
prefilling_size=10000000 prefilling_elapsed_millis=13.137s
finished prefilling to size 10000000 for expected size 10000000 keysum=100011363515279, performing 17164728 updates; total_prefilling_elapsed_ms=13137 ms)
pref_size=10000000
pref_millis=13137
prefill_elapsed_ms=13137
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6995627.844156257
REALTIME_END_PERF_FORMAT=6995637.844231992

###############################################################################
################################## 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 440 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 7 60 440 3289 24682 193504 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1527351
tree_stats_numNodesAtDepth=1 1 7 60 440 3289 24682 193504 1527351
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10000743
tree_stats_avgDegreeAtDepth=1 7 8.57143 7.33333 7.475 7.50441 7.83988 7.89312 6.54777

tree_stats_height=9
tree_stats_numInternals=221984
tree_stats_numLeaves=1527351
tree_stats_numNodes=1749335
tree_stats_numKeys=10000743

tree_stats_avgDegreeInternal=7.88046
tree_stats_avgDegreeLeaves=6.54777
tree_stats_avgDegree=6.71688
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=456671
first_thread_announced_epoch_by_thread=20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880 20880
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=214780 213239 215517 213254 214986 212124 213480 214756 216222 214870 215109 213484 214800 214698 215864 215781 215094 211703 213421 213931 215878 215730 213302 213257 213250 213306 215412 214610 214581 212644 213425 214456 215509 215061 213521 213398 214248 214786 215616 215248 214565 210854 212623 214090 215335 215941 213271 212225
sum_num_inserts_total=10283255
sum_num_deletes_by_thread=212937 213871 215433 213616 214868 212006 213025 213701 215943 214410 214527 212849 214334 214308 216925 216910 215501 211734 213381 213791 213471 214052 214427 211960 212787 212858 215227 215166 214686 212084 213816 214626 216002 214777 214204 212983 213518 213953 215858 215780 214034 210643 213300 212872 214043 215663 214651 213292
sum_num_deletes_total=10274803
sum_num_searches_by_thread=3836030 3845935 3866769 3843894 3856398 3825866 3837980 3849613 3884463 3862196 3856752 3838405 3851229 3860994 3898027 3893897 3870625 3818933 3845079 3839569 3861930 3870374 3848308 3829231 3839159 3836251 3874557 3867158 3856376 3821204 3840440 3848232 3880060 3861199 3855864 3838032 3842354 3856636 3882640 3874019 3863134 3796578 3840194 3849220 3861100 3879234 3850322 3835090
sum_num_searches_total=184941550
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4263747 4273045 4297719 4270764 4286252 4249996 4264485 4278070 4316628 4291476 4286388 4264738 4280363 4290000 4330816 4326588 4301220 4242370 4271881 4267291 4291279 4300156 4276037 4254448 4265196 4262415 4305196 4296934 4285643 4245932 4267681 4277314 4311571 4291037 4283589 4264413 4270120 4285375 4314114 4305047 4291733 4218075 4266117 4276182 4290478 4310838 4278244 4260607
average_num_operations_total=4281241
stdev_num_operations_total=3256
sum_num_operations_total=205499608
min_num_operations_total=4218075
max_num_operations_total=4330816
first_time_thread_terminate_by_thread=10000127 10000088 10000088 10000087 10000088 10000087 10000088 10000088 10000088 10000087 10000087 10000087 10000088 10000088 10000088 10000088 10000088 10000088 10000088 10000087 10000088 10000088 10000087 10000087 10000088 10000087 10000088 10000087 10000088 10000087 10000088 10000088 10000088 10000087 10000088 10000088 10000088 10000088 10000088 10000088 10000088 10000087 10000088 10000088 10000088 10000088 10000088 10000088
min_time_thread_terminate_total=10000087
max_time_thread_terminate_total=10000127
first_time_thread_start_by_thread=9 5 5 5 6 6 5 5 5 5 6 5 5 6 5 6 5 5 6 5 5 6 5 5 4 5 5 5 6 6 5 5 5 5 6 5 5 6 5 6 5 5 6 5 5 6 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99998579680022
threads_final_size=10000743
final_keysum=99998579680022
final_size=10000743
validate_result=success
Validation OK.
Structural validation OK.

total_find=184941550
total_rq=0
total_inserts=10283255
total_deletes=10274803
total_updates=20558058
total_queries=184941550
total_ops=205499608
find_throughput=18494155
rq_throughput=0
update_throughput=2055805
query_throughput=18494155
total_throughput=20549960


total find                    : 184941550
total rq                      : 0
total inserts                 : 10283255
total deletes                 : 10274803
total updates                 : 20558058
total queries                 : 184941550
total ops                     : 205499608
find throughput               : 18494155
rq throughput                 : 0
update throughput             : 2055805
query throughput              : 18494155
total throughput              : 20549960

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=16.9881
PAPI_L3_TCM=5.77086
PAPI_TOT_CYC=6481.79
PAPI_TOT_INS=3825.66
garbage=905956991419371
total_execution_walltime=28.593s
REALTIME_MAIN_END_PERF_FORMAT=6995638.11411583
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 221131692
[slow htm]
[fallback]
total fallback commit         : 1532645
seconds global lock is held   : 20.9239
[time_cmd_output] time_elapsed_sec=28.90, faults_major=0, faults_minor=791998, mem_maxresident_kb=2988028, user_cputime=1358.99, sys_cputime=5.10, percent_cpu=4719%
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