__step=000097
__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=data000097.txt
__path_data=data/data000097.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994653.552132537
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.019s
prefilling_size=198590 prefilling_elapsed_millis=1.001s
prefilling_size=217638 prefilling_elapsed_millis=1.101s
prefilling_size=237206 prefilling_elapsed_millis=1.201s
prefilling_size=256508 prefilling_elapsed_millis=1.301s
prefilling_size=275480 prefilling_elapsed_millis=1.401s
prefilling_size=295002 prefilling_elapsed_millis=1.502s
prefilling_size=314448 prefilling_elapsed_millis=1.602s
prefilling_size=333995 prefilling_elapsed_millis=1.702s
prefilling_size=353327 prefilling_elapsed_millis=1.802s
prefilling_size=371847 prefilling_elapsed_millis=1.902s
prefilling_size=390533 prefilling_elapsed_millis=2.003s
prefilling_size=409804 prefilling_elapsed_millis=2.103s
prefilling_size=429140 prefilling_elapsed_millis=2.203s
prefilling_size=447721 prefilling_elapsed_millis=2.303s
prefilling_size=466853 prefilling_elapsed_millis=2.403s
prefilling_size=486019 prefilling_elapsed_millis=2.504s
prefilling_size=505136 prefilling_elapsed_millis=2.604s
prefilling_size=523773 prefilling_elapsed_millis=2.704s
prefilling_size=542312 prefilling_elapsed_millis=2.804s
prefilling_size=560981 prefilling_elapsed_millis=2.904s
prefilling_size=580060 prefilling_elapsed_millis=3.005s
prefilling_size=598808 prefilling_elapsed_millis=3.105s
prefilling_size=617649 prefilling_elapsed_millis=3.205s
prefilling_size=636412 prefilling_elapsed_millis=3.305s
prefilling_size=654943 prefilling_elapsed_millis=3.405s
prefilling_size=673230 prefilling_elapsed_millis=3.506s
prefilling_size=691614 prefilling_elapsed_millis=3.606s
prefilling_size=710365 prefilling_elapsed_millis=3.706s
prefilling_size=729157 prefilling_elapsed_millis=3.806s
prefilling_size=747458 prefilling_elapsed_millis=3.906s
prefilling_size=766083 prefilling_elapsed_millis=4.007s
prefilling_size=784897 prefilling_elapsed_millis=4.107s
prefilling_size=803618 prefilling_elapsed_millis=4.207s
prefilling_size=822512 prefilling_elapsed_millis=4.307s
prefilling_size=841014 prefilling_elapsed_millis=4.407s
prefilling_size=859379 prefilling_elapsed_millis=4.508s
prefilling_size=877566 prefilling_elapsed_millis=4.608s
prefilling_size=896410 prefilling_elapsed_millis=4.708s
prefilling_size=915022 prefilling_elapsed_millis=4.808s
prefilling_size=933305 prefilling_elapsed_millis=4.908s
prefilling_size=951648 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9048293 keys with 48 threads...
tid=0 thread_prefill_amount=24996 percent_done_estimate=13.3 elapsed_s=6 est_remaining_s=84 / 90
tid=0 thread_prefill_amount=29162 percent_done_estimate=15.5 elapsed_s=7 est_remaining_s=82 / 89
tid=0 thread_prefill_amount=33328 percent_done_estimate=17.7 elapsed_s=8 est_remaining_s=80 / 87
tid=0 thread_prefill_amount=37494 percent_done_estimate=19.9 elapsed_s=9 est_remaining_s=77 / 86
tid=0 thread_prefill_amount=41660 percent_done_estimate=22.1 elapsed_s=9 est_remaining_s=75 / 84
tid=0 thread_prefill_amount=45826 percent_done_estimate=24.3 elapsed_s=10 est_remaining_s=73 / 83
tid=0 thread_prefill_amount=49992 percent_done_estimate=26.5 elapsed_s=11 est_remaining_s=71 / 82
tid=0 thread_prefill_amount=54158 percent_done_estimate=28.7 elapsed_s=12 est_remaining_s=68 / 81
tid=0 thread_prefill_amount=58324 percent_done_estimate=30.9 elapsed_s=13 est_remaining_s=66 / 79
tid=0 thread_prefill_amount=62490 percent_done_estimate=33.2 elapsed_s=14 est_remaining_s=64 / 78
tid=0 thread_prefill_amount=66656 percent_done_estimate=35.4 elapsed_s=15 est_remaining_s=62 / 76
tid=0 thread_prefill_amount=70822 percent_done_estimate=37.6 elapsed_s=16 est_remaining_s=59 / 75
tid=0 thread_prefill_amount=74988 percent_done_estimate=39.8 elapsed_s=17 est_remaining_s=57 / 74
tid=0 thread_prefill_amount=79154 percent_done_estimate=42.0 elapsed_s=18 est_remaining_s=55 / 73
tid=0 thread_prefill_amount=83320 percent_done_estimate=44.2 elapsed_s=19 est_remaining_s=53 / 72
tid=0 thread_prefill_amount=87486 percent_done_estimate=46.4 elapsed_s=20 est_remaining_s=51 / 71
tid=0 thread_prefill_amount=91652 percent_done_estimate=48.6 elapsed_s=21 est_remaining_s=49 / 70
tid=0 thread_prefill_amount=95818 percent_done_estimate=50.8 elapsed_s=22 est_remaining_s=47 / 69
tid=0 thread_prefill_amount=99984 percent_done_estimate=53.0 elapsed_s=22 est_remaining_s=45 / 67
tid=0 thread_prefill_amount=104150 percent_done_estimate=55.3 elapsed_s=23 est_remaining_s=43 / 66
tid=0 thread_prefill_amount=108316 percent_done_estimate=57.5 elapsed_s=24 est_remaining_s=41 / 65
tid=0 thread_prefill_amount=112482 percent_done_estimate=59.7 elapsed_s=25 est_remaining_s=38 / 64
tid=0 thread_prefill_amount=116648 percent_done_estimate=61.9 elapsed_s=26 est_remaining_s=36 / 62
tid=0 thread_prefill_amount=120814 percent_done_estimate=64.1 elapsed_s=27 est_remaining_s=34 / 61
tid=0 thread_prefill_amount=124980 percent_done_estimate=66.3 elapsed_s=28 est_remaining_s=32 / 60
tid=0 thread_prefill_amount=129146 percent_done_estimate=68.5 elapsed_s=29 est_remaining_s=30 / 59
tid=0 thread_prefill_amount=133312 percent_done_estimate=70.7 elapsed_s=30 est_remaining_s=28 / 58
tid=0 thread_prefill_amount=137478 percent_done_estimate=72.9 elapsed_s=31 est_remaining_s=26 / 57
tid=0 thread_prefill_amount=141644 percent_done_estimate=75.1 elapsed_s=32 est_remaining_s=24 / 56
tid=0 thread_prefill_amount=145810 percent_done_estimate=77.4 elapsed_s=33 est_remaining_s=22 / 55
tid=0 thread_prefill_amount=149976 percent_done_estimate=79.6 elapsed_s=34 est_remaining_s=20 / 53
tid=0 thread_prefill_amount=154142 percent_done_estimate=81.8 elapsed_s=35 est_remaining_s=17 / 52
tid=0 thread_prefill_amount=158308 percent_done_estimate=84.0 elapsed_s=36 est_remaining_s=15 / 51
tid=0 thread_prefill_amount=162474 percent_done_estimate=86.2 elapsed_s=37 est_remaining_s=13 / 50
tid=0 thread_prefill_amount=166640 percent_done_estimate=88.4 elapsed_s=38 est_remaining_s=11 / 49
tid=0 thread_prefill_amount=170806 percent_done_estimate=90.6 elapsed_s=39 est_remaining_s=9 / 47
tid=0 thread_prefill_amount=174972 percent_done_estimate=92.8 elapsed_s=39 est_remaining_s=7 / 46
tid=0 thread_prefill_amount=179138 percent_done_estimate=95.0 elapsed_s=40 est_remaining_s=5 / 45
tid=0 thread_prefill_amount=183304 percent_done_estimate=97.2 elapsed_s=41 est_remaining_s=3 / 44
tid=0 thread_prefill_amount=187470 percent_done_estimate=99.5 elapsed_s=42 est_remaining_s=1 / 43
tid=0 thread_prefill_amount=191636 percent_done_estimate=101.7 elapsed_s=43 est_remaining_s=-2 / 42
tid=0 thread_prefill_amount=195802 percent_done_estimate=103.9 elapsed_s=44 est_remaining_s=-4 / 40
tid=0 thread_prefill_amount=199968 percent_done_estimate=106.1 elapsed_s=45 est_remaining_s=-6 / 39
tid=0 thread_prefill_amount=204134 percent_done_estimate=108.3 elapsed_s=46 est_remaining_s=-8 / 38
tid=0 thread_prefill_amount=208300 percent_done_estimate=110.5 elapsed_s=47 est_remaining_s=-10 / 37
timing_elapsed 46.8051s
prefilling_size=10000000 prefilling_elapsed_millis=46.917s
finished prefilling to size 10000000 for expected size 10000000 keysum=99997942291307, performing 14893534 updates; total_prefilling_elapsed_ms=46917 ms)
pref_size=10000000
pref_millis=46917
prefill_elapsed_ms=46917
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6994705.756155696
REALTIME_END_PERF_FORMAT=6994715.756245048

###############################################################################
################################## 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 399 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 57 399 2971 22469 174808 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1381075
tree_stats_numNodesAtDepth=1 1 8 57 399 2971 22469 174808 1381075
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9998410
tree_stats_avgDegreeAtDepth=1 8 7.125 7 7.44612 7.56277 7.77996 7.90053 7.23959

tree_stats_height=9
tree_stats_numInternals=200714
tree_stats_numLeaves=1381075
tree_stats_numNodes=1581789
tree_stats_numKeys=9998410

tree_stats_avgDegreeInternal=7.88081
tree_stats_avgDegreeLeaves=7.23959
tree_stats_avgDegree=7.32095
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=99167
first_thread_announced_epoch_by_thread=6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142 6142
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=50053256
sum_fasthtm_abort_total=107799997
sum_htmpostfix_commit_total=2551012
sum_htmpostfix_abort_total=10419166
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=74414051
sum_stmprefix_commit_total=3
sum_slow_commit_total=75809
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=59424 54872 54668 53701 52489 51504 60284 54594 55131 53363 53547 53043 59807 54078 54545 53586 53108 52552 61390 56294 54494 54471 53942 53157 59925 54928 54131 52949 53195 51816 59949 54960 55075 54178 53628 52134 60267 53961 54194 53403 53604 52666 61092 56329 54268 54133 53830 53281
sum_num_inserts_total=2635940
sum_num_deletes_by_thread=59181 54616 54482 53709 53120 51714 60092 54527 55080 53788 52882 53114 60266 53637 54216 53604 53327 52100 60829 55966 54083 53701 53955 53088 60249 54704 54056 53159 53182 51716 60225 54625 55111 54240 53432 52595 59823 54419 53893 53252 53475 52524 60929 55738 54386 54350 53887 53388
sum_num_deletes_total=2632435
sum_num_searches_by_thread=1062098 986684 980861 968459 946361 933907 1091072 980384 988515 963290 954876 954919 1084134 971323 977712 965201 953415 942699 1095048 1012083 982112 973195 972303 948299 1078870 982631 974215 959259 955839 934000 1088221 991317 988268 976021 964095 943676 1078774 980206 971230 962102 960414 942285 1098955 1009468 981331 980746 964896 955936
sum_num_searches_total=47411705
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1180703 1096172 1090011 1075869 1051970 1037125 1211448 1089505 1098726 1070441 1061305 1061076 1204207 1079038 1086473 1072391 1059850 1047351 1217267 1124343 1090689 1081367 1080200 1054544 1199044 1092263 1082402 1065367 1062216 1037532 1208395 1100902 1098454 1084439 1071155 1048405 1198864 1088586 1079317 1068757 1067493 1047475 1220976 1121535 1089985 1089229 1072613 1062605
average_num_operations_total=1097501
stdev_num_operations_total=7619
sum_num_operations_total=52680080
min_num_operations_total=1037125
max_num_operations_total=1220976
first_time_thread_terminate_by_thread=10000146 10000143 10000089 10000128 10000137 10000120 10000095 10000092 10000119 10000135 10000156 10000093 10000089 10000146 10000100 10000089 10000107 10000122 10000114 10000134 10000262 10000090 10000089 10000129 10000100 10000089 10000140 10000102 10000089 10000127 10000106 10000095 10000098 10000120 10000259 10000113 10000089 10000130 10000113 10000125 10000124 10000089 10000089 10000132 10000133 10000103 10000089 10000089
min_time_thread_terminate_total=10000089
max_time_thread_terminate_total=10000262
first_time_thread_start_by_thread=9 5 5 5 5 4 5 4 4 5 5 5 5 5 4 5 5 4 5 4 5 5 5 5 4 4 5 5 5 4 5 4 4 5 5 5 5 5 4 5 5 4 5 4 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99979758274104
threads_final_size=9998410
final_keysum=99979758274104
final_size=9998410
validate_result=success
Validation OK.
Structural validation OK.

total_find=47411705
total_rq=0
total_inserts=2635940
total_deletes=2632435
total_updates=5268375
total_queries=47411705
total_ops=52680080
find_throughput=4741170
rq_throughput=0
update_throughput=526837
query_throughput=4741170
total_throughput=5268008


total find                    : 47411705
total rq                      : 0
total inserts                 : 2635940
total deletes                 : 2632435
total updates                 : 5268375
total queries                 : 47411705
total ops                     : 52680080
find throughput               : 4741170
rq throughput                 : 0
update throughput             : 526837
query throughput              : 4741170
total throughput              : 5268008

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=38.5365
PAPI_L3_TCM=7.20167
PAPI_TOT_CYC=22038.4
PAPI_TOT_INS=4335.98
garbage=237237629717297
total_execution_walltime=62.333s
REALTIME_MAIN_END_PERF_FORMAT=6994715.888246901
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=62.85, faults_major=0, faults_minor=1551889, mem_maxresident_kb=6207712, user_cputime=2784.80, sys_cputime=128.55, percent_cpu=4634%
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=1