__step=000273
__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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000273.txt
__path_data=data/data000273.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997910.24129925
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=2000000
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=234854 prefilling_elapsed_millis=1.001s
prefilling_size=255565 prefilling_elapsed_millis=1.101s
prefilling_size=275489 prefilling_elapsed_millis=1.201s
prefilling_size=295237 prefilling_elapsed_millis=1.301s
prefilling_size=314081 prefilling_elapsed_millis=1.402s
prefilling_size=332658 prefilling_elapsed_millis=1.502s
prefilling_size=350962 prefilling_elapsed_millis=1.602s
prefilling_size=368632 prefilling_elapsed_millis=1.702s
prefilling_size=386012 prefilling_elapsed_millis=1.802s
prefilling_size=402814 prefilling_elapsed_millis=1.903s
prefilling_size=419072 prefilling_elapsed_millis=2.003s
prefilling_size=434981 prefilling_elapsed_millis=2.103s
prefilling_size=450667 prefilling_elapsed_millis=2.203s
prefilling_size=465876 prefilling_elapsed_millis=2.303s
prefilling_size=480663 prefilling_elapsed_millis=2.404s
prefilling_size=494969 prefilling_elapsed_millis=2.504s
prefilling_size=508801 prefilling_elapsed_millis=2.604s
prefilling_size=522399 prefilling_elapsed_millis=2.704s
prefilling_size=535697 prefilling_elapsed_millis=2.804s
prefilling_size=548637 prefilling_elapsed_millis=2.905s
prefilling_size=561064 prefilling_elapsed_millis=3.005s
prefilling_size=573326 prefilling_elapsed_millis=3.105s
prefilling_size=585026 prefilling_elapsed_millis=3.205s
prefilling_size=596863 prefilling_elapsed_millis=3.305s
prefilling_size=608016 prefilling_elapsed_millis=3.406s
prefilling_size=619046 prefilling_elapsed_millis=3.506s
prefilling_size=630073 prefilling_elapsed_millis=3.606s
prefilling_size=640826 prefilling_elapsed_millis=3.706s
prefilling_size=650512 prefilling_elapsed_millis=3.806s
prefilling_size=660502 prefilling_elapsed_millis=3.907s
prefilling_size=670260 prefilling_elapsed_millis=4.007s
prefilling_size=679589 prefilling_elapsed_millis=4.107s
prefilling_size=688811 prefilling_elapsed_millis=4.207s
prefilling_size=697691 prefilling_elapsed_millis=4.307s
prefilling_size=706411 prefilling_elapsed_millis=4.408s
prefilling_size=714786 prefilling_elapsed_millis=4.508s
prefilling_size=722687 prefilling_elapsed_millis=4.608s
prefilling_size=730443 prefilling_elapsed_millis=4.708s
prefilling_size=738230 prefilling_elapsed_millis=4.808s
prefilling_size=745893 prefilling_elapsed_millis=4.909s
prefilling_size=753261 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 246739 keys with 48 threads...
tid=0 thread_prefill_amount=16664 percent_done_estimate=324.2 elapsed_s=5 est_remaining_s=-9 / -3
timing_elapsed 0.918439s
prefilling_size=1000000 prefilling_elapsed_millis=0.92s
finished prefilling to size 1000000 for expected size 1000000 keysum=999751918162, performing 3257277 updates; total_prefilling_elapsed_ms=920 ms)
pref_size=1000000
pref_millis=920
prefill_elapsed_ms=920
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997916.244156776
REALTIME_END_PERF_FORMAT=6997926.244238942

###############################################################################
################################## 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.007s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32760 64852 117812 158563 120111 40107 4502 89 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 8 613 10354 62970 150738 143141 50783 5684 111
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65465 128166 221533 270849 183248 55285 5773 111
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65465 128166 221533 270849 183248 55285 5773 111
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99808 1.96714 1.80927 1.50686 1.23311 1.08283 1.02299 1.00381 1

tree_stats_height=24
tree_stats_numInternals=571563
tree_stats_numLeaves=424402
tree_stats_numNodes=995965
tree_stats_numKeys=995965

tree_stats_avgDegreeInternal=1.74253
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42612
tree_stats_avgKeyDepth=18.3069


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=170293
first_thread_announced_epoch_by_thread=7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424 7424
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=50102138
sum_fasthtm_abort_total=106304995
sum_htmpostfix_commit_total=2560884
sum_htmpostfix_abort_total=15534806
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=74872113
sum_stmprefix_commit_total=68
sum_slow_commit_total=70641
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=54231 55086 56169 56250 52683 56902 54475 54575 54935 56274 51384 57211 54123 55351 55908 56756 50783 57320 54249 54564 54214 55273 51483 57422 54260 54650 55652 56398 52696 57157 54684 54881 55791 55930 51463 56718 53883 55648 56003 56326 50817 56721 53765 54547 54022 55712 51679 57242
sum_num_inserts_total=2634266
sum_num_deletes_by_thread=54174 54992 55974 56126 52645 57503 55103 54490 55389 56242 51516 57379 54181 55710 56179 56597 51189 57998 54158 54776 54510 55185 51570 56886 54033 54927 56055 56372 52355 57417 54405 54815 55597 55794 51606 56843 54266 55241 56393 56240 51077 57032 53619 54629 54622 55549 51458 57114
sum_num_deletes_total=2637931
sum_num_searches_by_thread=983766 988042 1004868 1004067 948706 1027788 985125 979413 991462 1013870 922489 1033232 970187 1008524 1007181 1021607 918682 1035750 974568 984016 981221 995104 930820 1025454 977676 987946 1005109 1018704 944053 1032369 986789 986361 1005894 1006025 927713 1026130 972226 994261 1011936 1015518 913380 1029694 969547 982462 978484 1002104 928756 1022455
sum_num_searches_total=47461534
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1092171 1098120 1117011 1116443 1054034 1142193 1094703 1088478 1101786 1126386 1025389 1147822 1078491 1119585 1119268 1134960 1020654 1151068 1082975 1093356 1089945 1105562 1033873 1139762 1085969 1097523 1116816 1131474 1049104 1146943 1095878 1096057 1117282 1117749 1030782 1139691 1080375 1105150 1124332 1128084 1015274 1143447 1076931 1091638 1087128 1113365 1031893 1136811
average_num_operations_total=1098619
stdev_num_operations_total=5297
sum_num_operations_total=52733731
min_num_operations_total=1015274
max_num_operations_total=1151068
first_time_thread_terminate_by_thread=10000171 10000148 10000121 10000167 10000160 10000118 10000151 10000170 10000128 10000162 10000153 10000163 10000124 10000115 10000154 10000134 10000083 10000141 10000082 10000082 10000165 10000147 10000122 10000164 10000133 10000168 10000151 10000144 10000149 10000108 10000138 10000169 10000081 10000134 10000166 10000158 10000161 10000084 10000106 10000132 10000103 10000136 10000141 10000142 10000138 10000087 10000157 10000081
min_time_thread_terminate_total=10000081
max_time_thread_terminate_total=10000171
first_time_thread_start_by_thread=10 5 5 4 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 4 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=10
sum_duration_all_ops_total=

threads_final_keysum=995127619845
threads_final_size=995965
final_keysum=995127619845
final_size=995965
validate_result=success
Validation OK.
Structural validation OK.

total_find=47461534
total_rq=0
total_inserts=2634266
total_deletes=2637931
total_updates=5272197
total_queries=47461534
total_ops=52733731
find_throughput=4746153
rq_throughput=0
update_throughput=527219
query_throughput=4746153
total_throughput=5273373


total find                    : 47461534
total rq                      : 0
total inserts                 : 2634266
total deletes                 : 2637931
total updates                 : 5272197
total queries                 : 47461534
total ops                     : 52733731
find throughput               : 4746153
rq throughput                 : 0
update throughput             : 527219
query throughput              : 4746153
total throughput              : 5273373

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.0464
PAPI_L3_TCM=3.5079
PAPI_TOT_CYC=21989.6
PAPI_TOT_INS=4093.99
garbage=23233281942584
total_execution_walltime=16.349s
REALTIME_MAIN_END_PERF_FORMAT=6997926.382725053
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=16.52, faults_major=0, faults_minor=143705, mem_maxresident_kb=575532, user_cputime=750.17, sys_cputime=28.64, percent_cpu=4714%
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