__step=000057
__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_sigouin_int_avl_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=data000057.txt
__path_data=data/data000057.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993079.826071528
binary=./brown_sigouin_int_avl_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_sigouin_int_avl_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=48
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.006s
prefilling_size=265670 prefilling_elapsed_millis=1.001s
prefilling_size=291960 prefilling_elapsed_millis=1.101s
prefilling_size=318057 prefilling_elapsed_millis=1.201s
prefilling_size=344229 prefilling_elapsed_millis=1.301s
prefilling_size=370492 prefilling_elapsed_millis=1.402s
prefilling_size=396495 prefilling_elapsed_millis=1.502s
prefilling_size=422539 prefilling_elapsed_millis=1.602s
prefilling_size=448454 prefilling_elapsed_millis=1.702s
prefilling_size=474305 prefilling_elapsed_millis=1.802s
prefilling_size=500113 prefilling_elapsed_millis=1.903s
prefilling_size=525699 prefilling_elapsed_millis=2.003s
prefilling_size=551419 prefilling_elapsed_millis=2.103s
prefilling_size=576987 prefilling_elapsed_millis=2.203s
prefilling_size=602352 prefilling_elapsed_millis=2.303s
prefilling_size=627968 prefilling_elapsed_millis=2.403s
prefilling_size=653287 prefilling_elapsed_millis=2.504s
prefilling_size=678440 prefilling_elapsed_millis=2.604s
prefilling_size=703838 prefilling_elapsed_millis=2.704s
prefilling_size=729123 prefilling_elapsed_millis=2.804s
prefilling_size=754323 prefilling_elapsed_millis=2.904s
prefilling_size=779439 prefilling_elapsed_millis=3.005s
prefilling_size=804573 prefilling_elapsed_millis=3.105s
prefilling_size=829477 prefilling_elapsed_millis=3.205s
prefilling_size=854351 prefilling_elapsed_millis=3.305s
prefilling_size=879245 prefilling_elapsed_millis=3.405s
prefilling_size=903962 prefilling_elapsed_millis=3.506s
prefilling_size=928562 prefilling_elapsed_millis=3.606s
prefilling_size=953145 prefilling_elapsed_millis=3.706s
prefilling_size=977857 prefilling_elapsed_millis=3.806s
prefilling_size=1002305 prefilling_elapsed_millis=3.906s
prefilling_size=1026673 prefilling_elapsed_millis=4.007s
prefilling_size=1050982 prefilling_elapsed_millis=4.107s
prefilling_size=1075482 prefilling_elapsed_millis=4.207s
prefilling_size=1099764 prefilling_elapsed_millis=4.307s
prefilling_size=1124151 prefilling_elapsed_millis=4.407s
prefilling_size=1148313 prefilling_elapsed_millis=4.507s
prefilling_size=1172489 prefilling_elapsed_millis=4.608s
prefilling_size=1196652 prefilling_elapsed_millis=4.708s
prefilling_size=1220551 prefilling_elapsed_millis=4.808s
prefilling_size=1244412 prefilling_elapsed_millis=4.908s
prefilling_size=1268276 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8731663 keys with 48 threads...
tid=0 thread_prefill_amount=29162 percent_done_estimate=16.0 elapsed_s=6 est_remaining_s=66 / 71
tid=0 thread_prefill_amount=33328 percent_done_estimate=18.3 elapsed_s=6 est_remaining_s=63 / 69
tid=0 thread_prefill_amount=37494 percent_done_estimate=20.6 elapsed_s=7 est_remaining_s=61 / 68
tid=0 thread_prefill_amount=41660 percent_done_estimate=22.9 elapsed_s=8 est_remaining_s=59 / 66
tid=0 thread_prefill_amount=45826 percent_done_estimate=25.2 elapsed_s=8 est_remaining_s=57 / 65
tid=0 thread_prefill_amount=49992 percent_done_estimate=27.5 elapsed_s=9 est_remaining_s=55 / 64
tid=0 thread_prefill_amount=54158 percent_done_estimate=29.8 elapsed_s=10 est_remaining_s=53 / 63
tid=0 thread_prefill_amount=58324 percent_done_estimate=32.1 elapsed_s=11 est_remaining_s=51 / 62
tid=0 thread_prefill_amount=62490 percent_done_estimate=34.4 elapsed_s=11 est_remaining_s=49 / 60
tid=0 thread_prefill_amount=66656 percent_done_estimate=36.6 elapsed_s=12 est_remaining_s=47 / 59
tid=0 thread_prefill_amount=70822 percent_done_estimate=38.9 elapsed_s=13 est_remaining_s=45 / 58
tid=0 thread_prefill_amount=74988 percent_done_estimate=41.2 elapsed_s=14 est_remaining_s=44 / 57
tid=0 thread_prefill_amount=79154 percent_done_estimate=43.5 elapsed_s=14 est_remaining_s=42 / 56
tid=0 thread_prefill_amount=83320 percent_done_estimate=45.8 elapsed_s=15 est_remaining_s=40 / 55
tid=0 thread_prefill_amount=87486 percent_done_estimate=48.1 elapsed_s=16 est_remaining_s=38 / 54
tid=0 thread_prefill_amount=91652 percent_done_estimate=50.4 elapsed_s=17 est_remaining_s=37 / 53
tid=0 thread_prefill_amount=95818 percent_done_estimate=52.7 elapsed_s=17 est_remaining_s=35 / 52
tid=0 thread_prefill_amount=99984 percent_done_estimate=55.0 elapsed_s=18 est_remaining_s=33 / 51
tid=0 thread_prefill_amount=104150 percent_done_estimate=57.3 elapsed_s=19 est_remaining_s=31 / 50
tid=0 thread_prefill_amount=108316 percent_done_estimate=59.5 elapsed_s=19 est_remaining_s=30 / 49
tid=0 thread_prefill_amount=112482 percent_done_estimate=61.8 elapsed_s=20 est_remaining_s=28 / 48
tid=0 thread_prefill_amount=116648 percent_done_estimate=64.1 elapsed_s=21 est_remaining_s=26 / 47
tid=0 thread_prefill_amount=120814 percent_done_estimate=66.4 elapsed_s=22 est_remaining_s=25 / 46
tid=0 thread_prefill_amount=124980 percent_done_estimate=68.7 elapsed_s=22 est_remaining_s=23 / 45
tid=0 thread_prefill_amount=129146 percent_done_estimate=71.0 elapsed_s=23 est_remaining_s=21 / 44
tid=0 thread_prefill_amount=133312 percent_done_estimate=73.3 elapsed_s=24 est_remaining_s=20 / 43
tid=0 thread_prefill_amount=137478 percent_done_estimate=75.6 elapsed_s=25 est_remaining_s=18 / 42
tid=0 thread_prefill_amount=141644 percent_done_estimate=77.9 elapsed_s=25 est_remaining_s=16 / 41
tid=0 thread_prefill_amount=145810 percent_done_estimate=80.2 elapsed_s=26 est_remaining_s=14 / 40
tid=0 thread_prefill_amount=149976 percent_done_estimate=82.4 elapsed_s=27 est_remaining_s=13 / 39
tid=0 thread_prefill_amount=154142 percent_done_estimate=84.7 elapsed_s=27 est_remaining_s=11 / 39
tid=0 thread_prefill_amount=158308 percent_done_estimate=87.0 elapsed_s=28 est_remaining_s=9 / 38
tid=0 thread_prefill_amount=162474 percent_done_estimate=89.3 elapsed_s=29 est_remaining_s=8 / 37
tid=0 thread_prefill_amount=166640 percent_done_estimate=91.6 elapsed_s=30 est_remaining_s=6 / 36
tid=0 thread_prefill_amount=170806 percent_done_estimate=93.9 elapsed_s=30 est_remaining_s=4 / 35
tid=0 thread_prefill_amount=174972 percent_done_estimate=96.2 elapsed_s=31 est_remaining_s=3 / 34
tid=0 thread_prefill_amount=179138 percent_done_estimate=98.5 elapsed_s=32 est_remaining_s=1 / 33
tid=0 thread_prefill_amount=183304 percent_done_estimate=100.8 elapsed_s=33 est_remaining_s=-1 / 32
tid=0 thread_prefill_amount=187470 percent_done_estimate=103.1 elapsed_s=33 est_remaining_s=-2 / 31
tid=0 thread_prefill_amount=191636 percent_done_estimate=105.3 elapsed_s=34 est_remaining_s=-4 / 30
tid=0 thread_prefill_amount=195802 percent_done_estimate=107.6 elapsed_s=35 est_remaining_s=-6 / 29
tid=0 thread_prefill_amount=199968 percent_done_estimate=109.9 elapsed_s=35 est_remaining_s=-7 / 28
tid=0 thread_prefill_amount=204134 percent_done_estimate=112.2 elapsed_s=36 est_remaining_s=-9 / 27
tid=0 thread_prefill_amount=208300 percent_done_estimate=114.5 elapsed_s=37 est_remaining_s=-10 / 26
timing_elapsed 32.0526s
prefilling_size=10000000 prefilling_elapsed_millis=32.129s
finished prefilling to size 10000000 for expected size 10000000 keysum=99981638672864, performing 15271491 updates; total_prefilling_elapsed_ms=32129 ms)
pref_size=10000000
pref_millis=32129
prefill_elapsed_ms=32129
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6993117.256155188
REALTIME_END_PERF_FORMAT=6993127.256228122

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

tree_stats_computeWalltime=0.047s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262042 518994 959065 1420506 1389639 737067 173625 14088 242 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 98 4621 67922 406061 1128306 1505988 909806 221280 17666 299
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262140 523615 1026987 1826567 2517945 2243055 1083431 235368 17908 299
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262140 523615 1026987 1826567 2517945 2243055 1083431 235368 17908 299
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99997 1.99784 1.97017 1.84471 1.60082 1.33893 1.15442 1.05699 1.01623 1.00318 1

tree_stats_height=28
tree_stats_numInternals=5737411
tree_stats_numLeaves=4262047
tree_stats_numNodes=9999458
tree_stats_numKeys=9999458

tree_stats_avgDegreeInternal=1.74285
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42623
tree_stats_avgKeyDepth=21.7116


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=157871
first_thread_announced_epoch_by_thread=8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116 8116
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=48879393
sum_fasthtm_abort_total=104543881
sum_htmpostfix_commit_total=2483159
sum_htmpostfix_abort_total=16191967
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=71175344
sum_stmprefix_commit_total=4
sum_slow_commit_total=81552
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=54247 54405 54439 53767 53819 53247 53673 54631 54029 53624 52913 52653 53902 54859 54071 53338 52800 52985 53449 53865 53165 52880 52998 52505 54420 54626 54709 53817 53302 53131 53946 54190 53650 53645 52671 52530 53873 54974 54004 53566 53012 53019 53400 54016 53882 52972 52749 52706
sum_num_inserts_total=2573074
sum_num_deletes_by_thread=53994 55158 54420 53367 53550 53147 53621 54210 53716 53719 52800 52680 53507 55184 54083 53535 53013 52823 53407 53936 53443 53402 52956 52275 54167 54402 54094 54009 53636 53289 53517 54298 53210 53359 53043 52308 54048 54702 54225 54014 52909 53148 53551 54276 53451 52794 53196 52495
sum_num_deletes_total=2572087
sum_num_searches_by_thread=971789 983688 974871 959900 963648 952502 963477 976526 970001 962806 952392 944825 961307 993524 971328 963169 952341 953904 959390 976074 957851 957060 951354 945390 976772 985486 973883 964306 962180 958868 969031 978718 962166 966347 949138 941248 975012 989135 978911 967000 956092 956449 966281 977037 968346 950503 958685 948236
sum_num_searches_total=46298947
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1080030 1093251 1083730 1067034 1071017 1058896 1070771 1085367 1077746 1070149 1058105 1050158 1068716 1103567 1079482 1070042 1058154 1059712 1066246 1083875 1064459 1063342 1057308 1050170 1085359 1094514 1082686 1072132 1069118 1065288 1076494 1087206 1069026 1073351 1054852 1046086 1082933 1098811 1087140 1074580 1062013 1062616 1073232 1085329 1075679 1056269 1064630 1053437
average_num_operations_total=1071752
stdev_num_operations_total=1941
sum_num_operations_total=51444108
min_num_operations_total=1046086
max_num_operations_total=1103567
first_time_thread_terminate_by_thread=10000133 10000073 10000080 10000105 10000107 10000101 10000119 10000074 10000132 10000073 10000105 10000130 10000073 10000109 10000097 10000073 10000132 10000073 10000127 10000092 10000111 10000122 10000095 10000073 10000074 10000073 10000121 10000078 10000073 10000115 10000073 10000125 10000075 10000073 10000113 10000084 10000074 10000072 10000118 10000074 10000116 10000129 10000074 10000072 10000076 10000128 10000124 10000073
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000133
first_time_thread_start_by_thread=9 5 5 5 5 5 6 5 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 6 5 5 5 5 5 6 6 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=99983919953556
threads_final_size=9999458
final_keysum=99983919953556
final_size=9999458
validate_result=success
Validation OK.
Structural validation OK.

total_find=46298947
total_rq=0
total_inserts=2573074
total_deletes=2572087
total_updates=5145161
total_queries=46298947
total_ops=51444108
find_throughput=4629894
rq_throughput=0
update_throughput=514516
query_throughput=4629894
total_throughput=5144410


total find                    : 46298947
total rq                      : 0
total inserts                 : 2573074
total deletes                 : 2572087
total updates                 : 5145161
total queries                 : 46298947
total ops                     : 51444108
find throughput               : 4629894
rq throughput                 : 0
update throughput             : 514516
query throughput              : 4629894
total throughput              : 5144410

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=43.4534
PAPI_L3_TCM=8.83161
PAPI_TOT_CYC=23084.2
PAPI_TOT_INS=4507.44
garbage=231512995781228
total_execution_walltime=47.601s
REALTIME_MAIN_END_PERF_FORMAT=6993127.428888874
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=47.82, faults_major=0, faults_minor=342988, mem_maxresident_kb=1372396, user_cputime=2186.79, sys_cputime=82.72, percent_cpu=4745%
timeout=false
algorithm=brown_sigouin_int_avl_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