__step=000313
__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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000313.txt
__path_data=data/data000313.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998522.234230473
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=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=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=185533 prefilling_elapsed_millis=1.001s
prefilling_size=202366 prefilling_elapsed_millis=1.101s
prefilling_size=219025 prefilling_elapsed_millis=1.201s
prefilling_size=235803 prefilling_elapsed_millis=1.302s
prefilling_size=251857 prefilling_elapsed_millis=1.402s
prefilling_size=268323 prefilling_elapsed_millis=1.502s
prefilling_size=284022 prefilling_elapsed_millis=1.602s
prefilling_size=299732 prefilling_elapsed_millis=1.702s
prefilling_size=314879 prefilling_elapsed_millis=1.803s
prefilling_size=329793 prefilling_elapsed_millis=1.903s
prefilling_size=344473 prefilling_elapsed_millis=2.003s
prefilling_size=359184 prefilling_elapsed_millis=2.103s
prefilling_size=373963 prefilling_elapsed_millis=2.203s
prefilling_size=388149 prefilling_elapsed_millis=2.304s
prefilling_size=401890 prefilling_elapsed_millis=2.404s
prefilling_size=415726 prefilling_elapsed_millis=2.504s
prefilling_size=429018 prefilling_elapsed_millis=2.604s
prefilling_size=442241 prefilling_elapsed_millis=2.704s
prefilling_size=454988 prefilling_elapsed_millis=2.805s
prefilling_size=467449 prefilling_elapsed_millis=2.905s
prefilling_size=479848 prefilling_elapsed_millis=3.005s
prefilling_size=492113 prefilling_elapsed_millis=3.105s
prefilling_size=504092 prefilling_elapsed_millis=3.205s
prefilling_size=515964 prefilling_elapsed_millis=3.306s
prefilling_size=527240 prefilling_elapsed_millis=3.406s
prefilling_size=538621 prefilling_elapsed_millis=3.506s
prefilling_size=550191 prefilling_elapsed_millis=3.606s
prefilling_size=561039 prefilling_elapsed_millis=3.706s
prefilling_size=571385 prefilling_elapsed_millis=3.806s
prefilling_size=581747 prefilling_elapsed_millis=3.907s
prefilling_size=592058 prefilling_elapsed_millis=4.007s
prefilling_size=602072 prefilling_elapsed_millis=4.107s
prefilling_size=611637 prefilling_elapsed_millis=4.207s
prefilling_size=621791 prefilling_elapsed_millis=4.307s
prefilling_size=631194 prefilling_elapsed_millis=4.408s
prefilling_size=640388 prefilling_elapsed_millis=4.508s
prefilling_size=649642 prefilling_elapsed_millis=4.608s
prefilling_size=658549 prefilling_elapsed_millis=4.708s
prefilling_size=667017 prefilling_elapsed_millis=4.808s
prefilling_size=675771 prefilling_elapsed_millis=4.909s
prefilling_size=683898 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 316065 keys with 48 threads...
tid=0 thread_prefill_amount=16664 percent_done_estimate=253.1 elapsed_s=5 est_remaining_s=-7 / -2
tid=0 thread_prefill_amount=20830 percent_done_estimate=316.3 elapsed_s=6 est_remaining_s=-10 / -3
timing_elapsed 1.51942s
prefilling_size=1000000 prefilling_elapsed_millis=1.523s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000052200642, performing 2971192 updates; total_prefilling_elapsed_ms=1523 ms)
pref_size=1000000
pref_millis=1523
prefill_elapsed_ms=1523
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6998529.56154929
REALTIME_END_PERF_FORMAT=6998539.56245271
###############################################################################
################################## 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 360 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.007s
tree_stats_numInternalsAtDepth=1 1 7 50 360 2832 21853 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 171995
tree_stats_numNodesAtDepth=1 1 7 50 360 2832 21853 171995
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1001959
tree_stats_avgDegreeAtDepth=1 7 7.14286 7.2 7.86667 7.71645 7.87054 5.82551
tree_stats_height=8
tree_stats_numInternals=25104
tree_stats_numLeaves=171995
tree_stats_numNodes=197099
tree_stats_numKeys=1001959
tree_stats_avgDegreeInternal=7.8513
tree_stats_avgDegreeLeaves=5.82551
tree_stats_avgDegree=6.08353
tree_stats_avgKeyDepth=7
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=93114
first_thread_announced_epoch_by_thread=4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4244 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246 4246
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=60533449
sum_fasthtm_abort_total=128654904
sum_htmpostfix_commit_total=3135813
sum_htmpostfix_abort_total=11149464
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=70402908
sum_stmprefix_commit_total=59
sum_slow_commit_total=42993
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=76233 65990 63734 63380 61930 62242 76746 66238 64895 64194 64007 63484 74989 64609 64122 64671 65223 63370 76780 66933 66276 65932 64567 64308 76060 66116 64789 62534 63032 62338 76130 65335 64565 64501 63665 63810 76739 65237 64355 63822 64377 63685 77728 65662 65770 64568 63071 65264
sum_num_inserts_total=3188006
sum_num_deletes_by_thread=75284 65692 64235 63465 62085 62068 76596 66088 64359 63924 63843 63626 75501 65353 63998 64985 64706 63639 77054 66856 65932 65582 64229 64710 75314 66900 64383 62433 62673 62275 76504 65483 65388 64601 63933 64212 76522 65036 63640 63832 64175 63577 78103 65997 65480 64784 63312 64985
sum_num_deletes_total=3187352
sum_num_searches_by_thread=1363210 1187690 1151123 1140293 1113302 1121507 1379918 1185122 1164831 1147215 1153125 1143077 1361376 1166143 1149906 1168542 1164683 1142435 1384417 1204968 1187238 1179268 1157042 1163915 1364606 1192774 1164140 1127470 1128799 1127299 1374750 1171867 1171822 1157662 1143225 1151937 1376211 1171158 1150088 1150372 1158429 1143960 1393956 1182528 1178090 1165510 1139875 1170082
sum_num_searches_total=57336956
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1514727 1319372 1279092 1267138 1237317 1245817 1533260 1317448 1294085 1275333 1280975 1270187 1511866 1296105 1278026 1298198 1294612 1269444 1538251 1338757 1319446 1310782 1285838 1292933 1515980 1325790 1293312 1252437 1254504 1251912 1527384 1302685 1301775 1286764 1270823 1279959 1529472 1301431 1278083 1278026 1286981 1271222 1549787 1314187 1309340 1294862 1266258 1300331
average_num_operations_total=1327339
stdev_num_operations_total=13566
sum_num_operations_total=63712314
min_num_operations_total=1237317
max_num_operations_total=1549787
first_time_thread_terminate_by_thread=10000154 10000107 10000152 10000247 10000133 10000114 10000090 10000138 10000153 10000090 10000142 10000150 10000145 10000106 10000143 10000127 10000135 10000090 10000147 10000111 10000117 10000090 10000145 10000107 10000109 10000141 10000137 10000090 10000132 10000124 10000090 10000135 10000155 10000090 10000244 10000150 10000122 10000147 10000090 10000118 10000129 10000140 10000151 10000090 10000119 10000132 10000123 10000090
min_time_thread_terminate_total=10000090
max_time_thread_terminate_total=10000247
first_time_thread_start_by_thread=8 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 4 5 5 5 4 5 5 4 4 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 4 5 5 5 4 5 5 4
min_time_thread_start_total=4
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=1001660039839
threads_final_size=1001959
final_keysum=1001660039839
final_size=1001959
validate_result=success
Validation OK.
Structural validation OK.
total_find=57336956
total_rq=0
total_inserts=3188006
total_deletes=3187352
total_updates=6375358
total_queries=57336956
total_ops=63712314
find_throughput=5733695
rq_throughput=0
update_throughput=637535
query_throughput=5733695
total_throughput=6371231
total find : 57336956
total rq : 0
total inserts : 3188006
total deletes : 3187352
total updates : 6375358
total queries : 57336956
total ops : 63712314
find throughput : 5733695
rq throughput : 0
update throughput : 637535
query throughput : 5733695
total throughput : 6371231
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=29.7166
PAPI_L3_TCM=2.1939
PAPI_TOT_CYC=18250.9
PAPI_TOT_INS=3605.99
garbage=28645914279574
total_execution_walltime=16.936s
REALTIME_MAIN_END_PERF_FORMAT=6998539.185211445
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=17.13, faults_major=0, faults_minor=288844, mem_maxresident_kb=1156028, user_cputime=775.49, sys_cputime=30.46, percent_cpu=4702%
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