__step=000098
__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.rhnorec_post -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=data000098.txt
__path_data=data/data000098.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994716.478527952
binary=./brown_abtree_tm_auto.debra.rhnorec_post
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


RH noREC w/postfix htm system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=196072 prefilling_elapsed_millis=1.001s
prefilling_size=215787 prefilling_elapsed_millis=1.101s
prefilling_size=235326 prefilling_elapsed_millis=1.201s
prefilling_size=254802 prefilling_elapsed_millis=1.301s
prefilling_size=274235 prefilling_elapsed_millis=1.402s
prefilling_size=293437 prefilling_elapsed_millis=1.502s
prefilling_size=312922 prefilling_elapsed_millis=1.602s
prefilling_size=331944 prefilling_elapsed_millis=1.702s
prefilling_size=351302 prefilling_elapsed_millis=1.802s
prefilling_size=370013 prefilling_elapsed_millis=1.903s
prefilling_size=389012 prefilling_elapsed_millis=2.003s
prefilling_size=408006 prefilling_elapsed_millis=2.103s
prefilling_size=426954 prefilling_elapsed_millis=2.203s
prefilling_size=445843 prefilling_elapsed_millis=2.303s
prefilling_size=464810 prefilling_elapsed_millis=2.404s
prefilling_size=483752 prefilling_elapsed_millis=2.504s
prefilling_size=502750 prefilling_elapsed_millis=2.604s
prefilling_size=521975 prefilling_elapsed_millis=2.704s
prefilling_size=540676 prefilling_elapsed_millis=2.804s
prefilling_size=559215 prefilling_elapsed_millis=2.905s
prefilling_size=577913 prefilling_elapsed_millis=3.005s
prefilling_size=597125 prefilling_elapsed_millis=3.105s
prefilling_size=616192 prefilling_elapsed_millis=3.205s
prefilling_size=634562 prefilling_elapsed_millis=3.305s
prefilling_size=652874 prefilling_elapsed_millis=3.406s
prefilling_size=671418 prefilling_elapsed_millis=3.506s
prefilling_size=689804 prefilling_elapsed_millis=3.606s
prefilling_size=708550 prefilling_elapsed_millis=3.706s
prefilling_size=727235 prefilling_elapsed_millis=3.806s
prefilling_size=745884 prefilling_elapsed_millis=3.907s
prefilling_size=764174 prefilling_elapsed_millis=4.007s
prefilling_size=782837 prefilling_elapsed_millis=4.107s
prefilling_size=801400 prefilling_elapsed_millis=4.207s
prefilling_size=820266 prefilling_elapsed_millis=4.307s
prefilling_size=838880 prefilling_elapsed_millis=4.408s
prefilling_size=857098 prefilling_elapsed_millis=4.508s
prefilling_size=875529 prefilling_elapsed_millis=4.608s
prefilling_size=894157 prefilling_elapsed_millis=4.708s
prefilling_size=912767 prefilling_elapsed_millis=4.808s
prefilling_size=931166 prefilling_elapsed_millis=4.909s
prefilling_size=949611 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9050329 keys with 48 threads...
tid=0 thread_prefill_amount=20830 percent_done_estimate=11.0 elapsed_s=6 est_remaining_s=102 / 107
tid=0 thread_prefill_amount=24996 percent_done_estimate=13.3 elapsed_s=7 est_remaining_s=98 / 105
tid=0 thread_prefill_amount=29162 percent_done_estimate=15.5 elapsed_s=8 est_remaining_s=95 / 103
tid=0 thread_prefill_amount=33328 percent_done_estimate=17.7 elapsed_s=9 est_remaining_s=92 / 101
tid=0 thread_prefill_amount=37494 percent_done_estimate=19.9 elapsed_s=10 est_remaining_s=89 / 99
tid=0 thread_prefill_amount=41660 percent_done_estimate=22.1 elapsed_s=11 est_remaining_s=86 / 97
tid=0 thread_prefill_amount=45826 percent_done_estimate=24.3 elapsed_s=12 est_remaining_s=84 / 96
tid=0 thread_prefill_amount=49992 percent_done_estimate=26.5 elapsed_s=13 est_remaining_s=81 / 94
tid=0 thread_prefill_amount=54158 percent_done_estimate=28.7 elapsed_s=14 est_remaining_s=79 / 93
tid=0 thread_prefill_amount=58324 percent_done_estimate=30.9 elapsed_s=15 est_remaining_s=76 / 91
tid=0 thread_prefill_amount=62490 percent_done_estimate=33.1 elapsed_s=16 est_remaining_s=74 / 90
tid=0 thread_prefill_amount=66656 percent_done_estimate=35.4 elapsed_s=17 est_remaining_s=71 / 88
tid=0 thread_prefill_amount=70822 percent_done_estimate=37.6 elapsed_s=18 est_remaining_s=69 / 87
tid=0 thread_prefill_amount=74988 percent_done_estimate=39.8 elapsed_s=19 est_remaining_s=66 / 86
tid=0 thread_prefill_amount=79154 percent_done_estimate=42.0 elapsed_s=21 est_remaining_s=64 / 84
tid=0 thread_prefill_amount=83320 percent_done_estimate=44.2 elapsed_s=22 est_remaining_s=61 / 83
tid=0 thread_prefill_amount=87486 percent_done_estimate=46.4 elapsed_s=23 est_remaining_s=59 / 81
tid=0 thread_prefill_amount=91652 percent_done_estimate=48.6 elapsed_s=24 est_remaining_s=57 / 80
tid=0 thread_prefill_amount=95818 percent_done_estimate=50.8 elapsed_s=25 est_remaining_s=54 / 79
tid=0 thread_prefill_amount=99984 percent_done_estimate=53.0 elapsed_s=26 est_remaining_s=52 / 78
tid=0 thread_prefill_amount=104150 percent_done_estimate=55.2 elapsed_s=27 est_remaining_s=49 / 76
tid=0 thread_prefill_amount=108316 percent_done_estimate=57.4 elapsed_s=28 est_remaining_s=47 / 75
tid=0 thread_prefill_amount=112482 percent_done_estimate=59.7 elapsed_s=29 est_remaining_s=44 / 73
tid=0 thread_prefill_amount=116648 percent_done_estimate=61.9 elapsed_s=30 est_remaining_s=42 / 72
tid=0 thread_prefill_amount=120814 percent_done_estimate=64.1 elapsed_s=31 est_remaining_s=40 / 71
tid=0 thread_prefill_amount=124980 percent_done_estimate=66.3 elapsed_s=32 est_remaining_s=37 / 69
tid=0 thread_prefill_amount=129146 percent_done_estimate=68.5 elapsed_s=33 est_remaining_s=35 / 68
tid=0 thread_prefill_amount=133312 percent_done_estimate=70.7 elapsed_s=34 est_remaining_s=32 / 67
tid=0 thread_prefill_amount=137478 percent_done_estimate=72.9 elapsed_s=36 est_remaining_s=30 / 65
tid=0 thread_prefill_amount=141644 percent_done_estimate=75.1 elapsed_s=37 est_remaining_s=27 / 64
tid=0 thread_prefill_amount=145810 percent_done_estimate=77.3 elapsed_s=38 est_remaining_s=25 / 63
tid=0 thread_prefill_amount=149976 percent_done_estimate=79.5 elapsed_s=39 est_remaining_s=22 / 61
tid=0 thread_prefill_amount=154142 percent_done_estimate=81.8 elapsed_s=40 est_remaining_s=20 / 60
tid=0 thread_prefill_amount=158308 percent_done_estimate=84.0 elapsed_s=41 est_remaining_s=18 / 58
tid=0 thread_prefill_amount=162474 percent_done_estimate=86.2 elapsed_s=42 est_remaining_s=15 / 57
tid=0 thread_prefill_amount=166640 percent_done_estimate=88.4 elapsed_s=43 est_remaining_s=13 / 56
tid=0 thread_prefill_amount=170806 percent_done_estimate=90.6 elapsed_s=44 est_remaining_s=10 / 54
tid=0 thread_prefill_amount=174972 percent_done_estimate=92.8 elapsed_s=45 est_remaining_s=8 / 53
tid=0 thread_prefill_amount=179138 percent_done_estimate=95.0 elapsed_s=46 est_remaining_s=5 / 52
tid=0 thread_prefill_amount=183304 percent_done_estimate=97.2 elapsed_s=48 est_remaining_s=3 / 51
tid=0 thread_prefill_amount=187470 percent_done_estimate=99.4 elapsed_s=49 est_remaining_s=1 / 49
tid=0 thread_prefill_amount=191636 percent_done_estimate=101.6 elapsed_s=50 est_remaining_s=-2 / 48
tid=0 thread_prefill_amount=195802 percent_done_estimate=103.8 elapsed_s=51 est_remaining_s=-4 / 46
tid=0 thread_prefill_amount=199968 percent_done_estimate=106.1 elapsed_s=51 est_remaining_s=-7 / 45
tid=0 thread_prefill_amount=204134 percent_done_estimate=108.3 elapsed_s=52 est_remaining_s=-9 / 43
timing_elapsed 47.0296s
prefilling_size=10000000 prefilling_elapsed_millis=47.142s
finished prefilling to size 10000000 for expected size 10000000 keysum=100002650219727, performing 14885848 updates; total_prefilling_elapsed_ms=47142 ms)
pref_size=10000000
pref_millis=47142
prefill_elapsed_ms=47142
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6994768.920157012
REALTIME_END_PERF_FORMAT=6994778.920234318

###############################################################################
################################## 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 396 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 8 53 396 2975 22458 174784 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1380860
tree_stats_numNodesAtDepth=1 1 8 53 396 2975 22458 174784 1380860
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10002332
tree_stats_avgDegreeAtDepth=1 8 6.625 7.4717 7.51263 7.54891 7.78271 7.90038 7.24355

tree_stats_height=9
tree_stats_numInternals=200676
tree_stats_numLeaves=1380860
tree_stats_numNodes=1581536
tree_stats_numKeys=10002332

tree_stats_avgDegreeInternal=7.88104
tree_stats_avgDegreeLeaves=7.24355
tree_stats_avgDegree=7.32444
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=96813
first_thread_announced_epoch_by_thread=5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5884 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5886 5884 5886 5886 5886 5886 5886 5886 5886
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=50091953
sum_fasthtm_abort_total=107694444
sum_htmpostfix_commit_total=2550726
sum_htmpostfix_abort_total=10447433
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=76088595
sum_stmprefix_commit_total=3
sum_slow_commit_total=77189
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=53750 54033 55379 55175 52097 56068 54406 54146 57389 55965 53301 58265 54866 54776 55729 55529 53315 57542 54771 53118 55360 55446 52884 56593 53558 53678 55453 55710 52986 56775 53698 54543 55800 55340 53138 57948 54950 54485 55648 55556 52854 57985 53373 53528 54838 55146 52632 57319
sum_num_inserts_total=2636844
sum_num_deletes_by_thread=53469 54149 55300 55243 52608 55749 54438 54023 57695 55663 53099 58493 55072 54686 55740 55472 53082 57469 54537 52808 54776 55141 52828 56728 53145 54069 55800 55772 53012 56372 54019 54103 56355 55605 52961 57427 55166 54356 55792 55151 52744 57667 53353 53133 55049 55356 52462 56834
sum_num_deletes_total=2633971
sum_num_searches_by_thread=963888 972883 995266 996293 942813 1002906 978351 975080 1031949 1006069 961169 1048064 985286 988847 1004761 1001385 960293 1031678 980145 955791 989282 994101 950837 1025517 961010 973807 1009143 1003023 959667 1015465 966961 976402 1013858 994896 952135 1036741 995278 978476 1003881 998844 948489 1040319 963044 956714 990854 995445 947517 1024433
sum_num_searches_total=47449056
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1071107 1081065 1105945 1106711 1047518 1114723 1087195 1083249 1147033 1117697 1067569 1164822 1095224 1098309 1116230 1112386 1066690 1146689 1089453 1061717 1099418 1104688 1056549 1138838 1067713 1081554 1120396 1114505 1065665 1128612 1074678 1085048 1126013 1105841 1058234 1152116 1105394 1087317 1115321 1109551 1054087 1155971 1069770 1063375 1100741 1105947 1052611 1138586
average_num_operations_total=1098330
stdev_num_operations_total=4406
sum_num_operations_total=52719871
min_num_operations_total=1047518
max_num_operations_total=1164822
first_time_thread_terminate_by_thread=10000356 10000081 10000144 10000077 10000145 10000134 10000149 10000086 10000077 10000077 10000077 10000077 10000077 10000077 10000158 10000077 10001018 10000354 10000083 10000151 10000138 10000126 10000133 10000135 10000156 10000989 10000139 10000077 10000077 10000152 10000345 10000084 10000153 10000077 10000351 10000088 10000146 10000077 10000346 10000077 10001016 10000142 10000148 10000132 10000141 10000150 10000090 10000129
min_time_thread_terminate_total=10000077
max_time_thread_terminate_total=10001018
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 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
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100017988289613
threads_final_size=10002332
final_keysum=100017988289613
final_size=10002332
validate_result=success
Validation OK.
Structural validation OK.

total_find=47449056
total_rq=0
total_inserts=2636844
total_deletes=2633971
total_updates=5270815
total_queries=47449056
total_ops=52719871
find_throughput=4744905
rq_throughput=0
update_throughput=527081
query_throughput=4744905
total_throughput=5271987


total find                    : 47449056
total rq                      : 0
total inserts                 : 2636844
total deletes                 : 2633971
total updates                 : 5270815
total queries                 : 47449056
total ops                     : 52719871
find throughput               : 4744905
rq throughput                 : 0
update throughput             : 527081
query throughput              : 4744905
total throughput              : 5271987

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=38.7321
PAPI_L3_TCM=7.2094
PAPI_TOT_CYC=21942.4
PAPI_TOT_INS=4339.23
garbage=207021458783789
total_execution_walltime=62.567s
REALTIME_MAIN_END_PERF_FORMAT=6994779.51822794
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=63.11, faults_major=0, faults_minor=1578269, mem_maxresident_kb=6313328, user_cputime=2819.78, sys_cputime=134.23, percent_cpu=4680%
timeout=false
algorithm=brown_abtree_tm_auto.debra.rhnorec_post
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2