__step=000058
__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=data000058.txt
__path_data=data/data000058.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993127.712131298
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.004s
prefilling_size=263688 prefilling_elapsed_millis=1.001s
prefilling_size=289855 prefilling_elapsed_millis=1.101s
prefilling_size=316056 prefilling_elapsed_millis=1.201s
prefilling_size=342145 prefilling_elapsed_millis=1.301s
prefilling_size=367992 prefilling_elapsed_millis=1.402s
prefilling_size=393701 prefilling_elapsed_millis=1.502s
prefilling_size=419403 prefilling_elapsed_millis=1.602s
prefilling_size=445139 prefilling_elapsed_millis=1.702s
prefilling_size=470745 prefilling_elapsed_millis=1.802s
prefilling_size=496497 prefilling_elapsed_millis=1.903s
prefilling_size=522163 prefilling_elapsed_millis=2.003s
prefilling_size=547682 prefilling_elapsed_millis=2.103s
prefilling_size=573025 prefilling_elapsed_millis=2.203s
prefilling_size=598411 prefilling_elapsed_millis=2.303s
prefilling_size=623702 prefilling_elapsed_millis=2.404s
prefilling_size=648849 prefilling_elapsed_millis=2.504s
prefilling_size=674028 prefilling_elapsed_millis=2.604s
prefilling_size=699211 prefilling_elapsed_millis=2.704s
prefilling_size=724184 prefilling_elapsed_millis=2.804s
prefilling_size=749168 prefilling_elapsed_millis=2.905s
prefilling_size=774213 prefilling_elapsed_millis=3.005s
prefilling_size=799228 prefilling_elapsed_millis=3.105s
prefilling_size=823981 prefilling_elapsed_millis=3.205s
prefilling_size=848798 prefilling_elapsed_millis=3.305s
prefilling_size=873575 prefilling_elapsed_millis=3.406s
prefilling_size=898237 prefilling_elapsed_millis=3.506s
prefilling_size=922868 prefilling_elapsed_millis=3.606s
prefilling_size=947474 prefilling_elapsed_millis=3.706s
prefilling_size=971862 prefilling_elapsed_millis=3.806s
prefilling_size=996169 prefilling_elapsed_millis=3.906s
prefilling_size=1020367 prefilling_elapsed_millis=4.007s
prefilling_size=1044594 prefilling_elapsed_millis=4.107s
prefilling_size=1068881 prefilling_elapsed_millis=4.207s
prefilling_size=1092973 prefilling_elapsed_millis=4.307s
prefilling_size=1117126 prefilling_elapsed_millis=4.407s
prefilling_size=1141081 prefilling_elapsed_millis=4.508s
prefilling_size=1165083 prefilling_elapsed_millis=4.608s
prefilling_size=1189037 prefilling_elapsed_millis=4.708s
prefilling_size=1212843 prefilling_elapsed_millis=4.808s
prefilling_size=1236504 prefilling_elapsed_millis=4.908s
prefilling_size=1260300 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8739642 keys with 48 threads...
tid=0 thread_prefill_amount=29162 percent_done_estimate=16.0 elapsed_s=5 est_remaining_s=64 / 69
tid=0 thread_prefill_amount=33328 percent_done_estimate=18.3 elapsed_s=6 est_remaining_s=61 / 67
tid=0 thread_prefill_amount=37494 percent_done_estimate=20.6 elapsed_s=7 est_remaining_s=59 / 66
tid=0 thread_prefill_amount=41660 percent_done_estimate=22.9 elapsed_s=8 est_remaining_s=57 / 65
tid=0 thread_prefill_amount=45826 percent_done_estimate=25.2 elapsed_s=8 est_remaining_s=55 / 63
tid=0 thread_prefill_amount=49992 percent_done_estimate=27.5 elapsed_s=9 est_remaining_s=53 / 62
tid=0 thread_prefill_amount=54158 percent_done_estimate=29.7 elapsed_s=10 est_remaining_s=51 / 61
tid=0 thread_prefill_amount=58324 percent_done_estimate=32.0 elapsed_s=10 est_remaining_s=49 / 60
tid=0 thread_prefill_amount=62490 percent_done_estimate=34.3 elapsed_s=11 est_remaining_s=48 / 59
tid=0 thread_prefill_amount=66656 percent_done_estimate=36.6 elapsed_s=12 est_remaining_s=46 / 58
tid=0 thread_prefill_amount=70822 percent_done_estimate=38.9 elapsed_s=12 est_remaining_s=44 / 57
tid=0 thread_prefill_amount=74988 percent_done_estimate=41.2 elapsed_s=13 est_remaining_s=42 / 56
tid=0 thread_prefill_amount=79154 percent_done_estimate=43.5 elapsed_s=14 est_remaining_s=41 / 55
tid=0 thread_prefill_amount=83320 percent_done_estimate=45.8 elapsed_s=15 est_remaining_s=39 / 54
tid=0 thread_prefill_amount=87486 percent_done_estimate=48.0 elapsed_s=15 est_remaining_s=37 / 53
tid=0 thread_prefill_amount=91652 percent_done_estimate=50.3 elapsed_s=16 est_remaining_s=36 / 52
tid=0 thread_prefill_amount=95818 percent_done_estimate=52.6 elapsed_s=17 est_remaining_s=34 / 51
tid=0 thread_prefill_amount=99984 percent_done_estimate=54.9 elapsed_s=17 est_remaining_s=32 / 50
tid=0 thread_prefill_amount=104150 percent_done_estimate=57.2 elapsed_s=18 est_remaining_s=31 / 49
tid=0 thread_prefill_amount=108316 percent_done_estimate=59.5 elapsed_s=19 est_remaining_s=29 / 48
tid=0 thread_prefill_amount=112482 percent_done_estimate=61.8 elapsed_s=20 est_remaining_s=27 / 47
tid=0 thread_prefill_amount=116648 percent_done_estimate=64.1 elapsed_s=20 est_remaining_s=26 / 46
tid=0 thread_prefill_amount=120814 percent_done_estimate=66.4 elapsed_s=21 est_remaining_s=24 / 45
tid=0 thread_prefill_amount=124980 percent_done_estimate=68.6 elapsed_s=22 est_remaining_s=22 / 44
tid=0 thread_prefill_amount=129146 percent_done_estimate=70.9 elapsed_s=22 est_remaining_s=21 / 43
tid=0 thread_prefill_amount=133312 percent_done_estimate=73.2 elapsed_s=23 est_remaining_s=19 / 42
tid=0 thread_prefill_amount=137478 percent_done_estimate=75.5 elapsed_s=24 est_remaining_s=17 / 41
tid=0 thread_prefill_amount=141644 percent_done_estimate=77.8 elapsed_s=25 est_remaining_s=16 / 40
tid=0 thread_prefill_amount=145810 percent_done_estimate=80.1 elapsed_s=25 est_remaining_s=14 / 39
tid=0 thread_prefill_amount=149976 percent_done_estimate=82.4 elapsed_s=26 est_remaining_s=12 / 38
tid=0 thread_prefill_amount=154142 percent_done_estimate=84.7 elapsed_s=27 est_remaining_s=11 / 37
tid=0 thread_prefill_amount=158308 percent_done_estimate=86.9 elapsed_s=27 est_remaining_s=9 / 37
tid=0 thread_prefill_amount=162474 percent_done_estimate=89.2 elapsed_s=28 est_remaining_s=8 / 36
tid=0 thread_prefill_amount=166640 percent_done_estimate=91.5 elapsed_s=29 est_remaining_s=6 / 35
tid=0 thread_prefill_amount=170806 percent_done_estimate=93.8 elapsed_s=29 est_remaining_s=4 / 34
tid=0 thread_prefill_amount=174972 percent_done_estimate=96.1 elapsed_s=30 est_remaining_s=3 / 33
tid=0 thread_prefill_amount=179138 percent_done_estimate=98.4 elapsed_s=31 est_remaining_s=1 / 32
tid=0 thread_prefill_amount=183304 percent_done_estimate=100.7 elapsed_s=32 est_remaining_s=-0 / 31
tid=0 thread_prefill_amount=187470 percent_done_estimate=103.0 elapsed_s=32 est_remaining_s=-2 / 30
tid=0 thread_prefill_amount=191636 percent_done_estimate=105.3 elapsed_s=33 est_remaining_s=-4 / 29
tid=0 thread_prefill_amount=195802 percent_done_estimate=107.5 elapsed_s=34 est_remaining_s=-5 / 28
tid=0 thread_prefill_amount=199968 percent_done_estimate=109.8 elapsed_s=34 est_remaining_s=-7 / 27
tid=0 thread_prefill_amount=204134 percent_done_estimate=112.1 elapsed_s=35 est_remaining_s=-9 / 27
tid=0 thread_prefill_amount=208300 percent_done_estimate=114.4 elapsed_s=36 est_remaining_s=-10 / 26
timing_elapsed 32.1901s
prefilling_size=10000000 prefilling_elapsed_millis=32.267s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994870203470, performing 15260695 updates; total_prefilling_elapsed_ms=32267 ms)
pref_size=10000000
pref_millis=32267
prefill_elapsed_ms=32267
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6993165.268161321
REALTIME_END_PERF_FORMAT=6993175.268239340
###############################################################################
################################## 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 131071 262064 520167 967934 1442521 1399099 720493 152887 8972 76 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 75 3591 62652 404286 1155670 1530936 897196 195337 11198 89
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262139 523758 1030586 1846807 2554769 2251429 1050083 204309 11274 89
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262139 523758 1030586 1846807 2554769 2251429 1050083 204309 11274 89
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99997 1.9983 1.97453 1.85279 1.60225 1.33362 1.14639 1.04897 1.01127 1.00115 1
tree_stats_height=28
tree_stats_numInternals=5736355
tree_stats_numLeaves=4261031
tree_stats_numNodes=9997386
tree_stats_numKeys=9997386
tree_stats_avgDegreeInternal=1.74281
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42621
tree_stats_avgKeyDepth=21.6909
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=157823
first_thread_announced_epoch_by_thread=8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160 8160
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=48806912
sum_fasthtm_abort_total=104680251
sum_htmpostfix_commit_total=2482933
sum_htmpostfix_abort_total=16116420
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=73214666
sum_stmprefix_commit_total=5
sum_slow_commit_total=81738
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=55056 54141 53558 53565 53781 52279 55199 53541 53729 52499 52171 52244 55068 54300 54178 53352 52881 52066 55102 54035 53339 52496 52423 51742 55470 53859 54190 53668 53245 52566 55038 53531 53387 52899 52092 52014 55225 53993 54119 53655 53251 51912 54876 53916 53086 53313 51908 52409
sum_num_inserts_total=2566367
sum_num_deletes_by_thread=55004 53558 53947 53634 52950 52681 55201 53673 53544 53137 52243 52313 55390 54285 54347 53425 53170 52217 55111 53464 53401 53452 52610 52119 55398 53820 54242 53789 52894 53086 55201 53632 53586 52441 52594 52135 54774 54477 54098 54021 52937 51609 55023 53239 52948 53178 52458 52065
sum_num_deletes_total=2568521
sum_num_searches_by_thread=986258 976885 965078 969542 955806 946075 994579 963452 966734 944485 939516 948337 992566 978606 979113 965231 953244 941538 993686 966217 962771 951075 944542 931842 998072 972228 979021 974984 962870 949054 995183 965076 961780 943163 941216 936360 990366 975392 980543 966067 949134 934903 988027 964512 953321 955057 941596 941597
sum_num_searches_total=46236700
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1096318 1084584 1072583 1076741 1062537 1051035 1104979 1070666 1074007 1050121 1043930 1052894 1103024 1087191 1087638 1072008 1059295 1045821 1103899 1073716 1069511 1057023 1049575 1035703 1108940 1079907 1087453 1082441 1069009 1054706 1105422 1072239 1068753 1048503 1045902 1040509 1100365 1083862 1088760 1073743 1055322 1038424 1097926 1071667 1059355 1061548 1045962 1046071
average_num_operations_total=1070241
stdev_num_operations_total=2978
sum_num_operations_total=51371588
min_num_operations_total=1035703
max_num_operations_total=1108940
first_time_thread_terminate_by_thread=10000127 10000130 10000135 10000077 10000113 10000109 10000079 10000118 10000134 10000133 10000123 10000120 10000098 10000078 10000127 10000134 10000122 10000101 10000114 10000128 10000078 10000099 10000137 10000079 10000096 10000131 10000077 10000078 10000139 10000078 10000077 10000119 10000124 10000138 10000077 10000103 10000078 10000078 10000111 10000115 10000079 10000129 10000104 10000093 10000079 10000079 10000137 10000126
min_time_thread_terminate_total=10000077
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=12 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=12
sum_duration_all_ops_total=
threads_final_keysum=99964857789125
threads_final_size=9997386
final_keysum=99964857789125
final_size=9997386
validate_result=success
Validation OK.
Structural validation OK.
total_find=46236700
total_rq=0
total_inserts=2566367
total_deletes=2568521
total_updates=5134888
total_queries=46236700
total_ops=51371588
find_throughput=4623670
rq_throughput=0
update_throughput=513488
query_throughput=4623670
total_throughput=5137158
total find : 46236700
total rq : 0
total inserts : 2566367
total deletes : 2568521
total updates : 5134888
total queries : 46236700
total ops : 51371588
find throughput : 4623670
rq throughput : 0
update throughput : 513488
query throughput : 4623670
total throughput : 5137158
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=43.8362
PAPI_L3_TCM=8.82618
PAPI_TOT_CYC=23082.6
PAPI_TOT_INS=4519
garbage=226517038512703
total_execution_walltime=47.727s
REALTIME_MAIN_END_PERF_FORMAT=6993175.440292414
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=47.95, faults_major=0, faults_minor=347675, mem_maxresident_kb=1391316, user_cputime=2184.06, sys_cputime=84.97, percent_cpu=4731%
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=2