__step=000314
__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=data000314.txt
__path_data=data/data000314.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998539.440628629
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.004s
prefilling_size=187632 prefilling_elapsed_millis=1.001s
prefilling_size=205061 prefilling_elapsed_millis=1.101s
prefilling_size=221832 prefilling_elapsed_millis=1.201s
prefilling_size=238512 prefilling_elapsed_millis=1.301s
prefilling_size=255007 prefilling_elapsed_millis=1.402s
prefilling_size=271304 prefilling_elapsed_millis=1.502s
prefilling_size=287853 prefilling_elapsed_millis=1.602s
prefilling_size=303813 prefilling_elapsed_millis=1.702s
prefilling_size=319567 prefilling_elapsed_millis=1.802s
prefilling_size=334927 prefilling_elapsed_millis=1.903s
prefilling_size=349665 prefilling_elapsed_millis=2.003s
prefilling_size=364371 prefilling_elapsed_millis=2.103s
prefilling_size=379067 prefilling_elapsed_millis=2.203s
prefilling_size=393221 prefilling_elapsed_millis=2.303s
prefilling_size=407424 prefilling_elapsed_millis=2.404s
prefilling_size=421425 prefilling_elapsed_millis=2.504s
prefilling_size=435027 prefilling_elapsed_millis=2.604s
prefilling_size=448575 prefilling_elapsed_millis=2.704s
prefilling_size=461613 prefilling_elapsed_millis=2.804s
prefilling_size=474522 prefilling_elapsed_millis=2.905s
prefilling_size=487036 prefilling_elapsed_millis=3.005s
prefilling_size=499373 prefilling_elapsed_millis=3.105s
prefilling_size=511668 prefilling_elapsed_millis=3.205s
prefilling_size=523690 prefilling_elapsed_millis=3.305s
prefilling_size=535757 prefilling_elapsed_millis=3.406s
prefilling_size=547813 prefilling_elapsed_millis=3.506s
prefilling_size=559258 prefilling_elapsed_millis=3.606s
prefilling_size=570481 prefilling_elapsed_millis=3.706s
prefilling_size=581213 prefilling_elapsed_millis=3.806s
prefilling_size=592284 prefilling_elapsed_millis=3.907s
prefilling_size=602795 prefilling_elapsed_millis=4.007s
prefilling_size=613323 prefilling_elapsed_millis=4.107s
prefilling_size=623707 prefilling_elapsed_millis=4.207s
prefilling_size=633493 prefilling_elapsed_millis=4.307s
prefilling_size=642923 prefilling_elapsed_millis=4.408s
prefilling_size=652443 prefilling_elapsed_millis=4.508s
prefilling_size=661696 prefilling_elapsed_millis=4.608s
prefilling_size=671013 prefilling_elapsed_millis=4.708s
prefilling_size=680229 prefilling_elapsed_millis=4.808s
prefilling_size=689270 prefilling_elapsed_millis=4.908s
prefilling_size=698265 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 301691 keys with 48 threads...
tid=0 thread_prefill_amount=16664 percent_done_estimate=265.1 elapsed_s=5 est_remaining_s=-8 / -2
tid=0 thread_prefill_amount=20830 percent_done_estimate=331.4 elapsed_s=6 est_remaining_s=-10 / -4
timing_elapsed 1.4523s
prefilling_size=1000000 prefilling_elapsed_millis=1.455s
finished prefilling to size 1000000 for expected size 1000000 keysum=999823606056, performing 2935989 updates; total_prefilling_elapsed_ms=1455 ms)
pref_size=1000000
pref_millis=1455
prefill_elapsed_ms=1456
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6998546.176152308
REALTIME_END_PERF_FORMAT=6998556.176243978
###############################################################################
################################## 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 372 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.008s
tree_stats_numInternalsAtDepth=1 1 8 53 372 2780 21852 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 171786
tree_stats_numNodesAtDepth=1 1 8 53 372 2780 21852 171786
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 999671
tree_stats_avgDegreeAtDepth=1 8 6.625 7.01887 7.47312 7.86043 7.86134 5.81928
tree_stats_height=8
tree_stats_numInternals=25067
tree_stats_numLeaves=171786
tree_stats_numNodes=196853
tree_stats_numKeys=999671
tree_stats_avgDegreeInternal=7.85307
tree_stats_avgDegreeLeaves=5.81928
tree_stats_avgDegree=6.07826
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=99296
first_thread_announced_epoch_by_thread=4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4484 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486 4486
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=60681959
sum_fasthtm_abort_total=129161572
sum_htmpostfix_commit_total=3141893
sum_htmpostfix_abort_total=11121401
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=71546978
sum_stmprefix_commit_total=46
sum_slow_commit_total=43583
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=68306 68738 62203 69355 67986 66928 67838 67210 62779 67108 67350 67190 67874 69906 63695 68458 67021 66931 67063 66177 59992 68168 65380 65437 68811 68908 61862 68392 67574 65830 68008 67023 62288 67517 67267 67039 68121 69008 62775 68322 67165 67012 66989 66682 60499 67512 66573 65247
sum_num_inserts_total=3195517
sum_num_deletes_by_thread=68971 69038 61385 69196 68120 66678 67766 67638 62861 67832 66940 67001 68312 69880 63476 69073 66170 67154 67294 66269 59851 68412 65190 65518 68683 68717 62508 67939 67731 65835 67977 67587 62078 67603 66906 66210 68736 68568 63404 68302 67520 67226 66822 67405 60271 67713 66201 65013
sum_num_deletes_total=3196980
sum_num_searches_by_thread=1238438 1238913 1110234 1250383 1221701 1198231 1218245 1208324 1119776 1213356 1211260 1208019 1229593 1252593 1145816 1228651 1198244 1206582 1205179 1186887 1075714 1230106 1174877 1183744 1233122 1232324 1122082 1224517 1214462 1183122 1226025 1215117 1122027 1215613 1206699 1197751 1223805 1235464 1136620 1234220 1216015 1202894 1204486 1209994 1086749 1215642 1189496 1171872
sum_num_searches_total=57474984
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1375715 1376689 1233822 1388934 1357807 1331837 1353849 1343172 1245416 1348296 1345550 1342210 1365779 1392379 1272987 1366182 1331435 1340667 1339536 1319333 1195557 1366686 1305447 1314699 1370616 1369949 1246452 1360848 1349767 1314787 1362010 1349727 1246393 1350733 1340872 1331000 1360662 1373040 1262799 1370844 1350700 1337132 1338297 1344081 1207519 1350867 1322270 1302132
average_num_operations_total=1330572
stdev_num_operations_total=6842
sum_num_operations_total=63867481
min_num_operations_total=1195557
max_num_operations_total=1392379
first_time_thread_terminate_by_thread=10000179 10000171 10000152 10000157 10000169 10000152 10000159 10000173 10000091 10000185 10000193 10000471 10000185 10000091 10000092 10000193 10000187 10000188 10000181 10000170 10000164 10000200 10000174 10000163 10000177 10000203 10000149 10000182 10000102 10000189 10000147 10000159 10000191 10000207 10000171 10000178 10000190 10000097 10000161 10000210 10000153 10000091 10000167 10000195 10000098 10000184 10000166 10000091
min_time_thread_terminate_total=10000091
max_time_thread_terminate_total=10000471
first_time_thread_start_by_thread=9 5 5 5 5 5 4 4 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 4 5 5 5 5 5 4 4 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=1000038495767
threads_final_size=999671
final_keysum=1000038495767
final_size=999671
validate_result=success
Validation OK.
Structural validation OK.
total_find=57474984
total_rq=0
total_inserts=3195517
total_deletes=3196980
total_updates=6392497
total_queries=57474984
total_ops=63867481
find_throughput=5747498
rq_throughput=0
update_throughput=639249
query_throughput=5747498
total_throughput=6386748
total find : 57474984
total rq : 0
total inserts : 3195517
total deletes : 3196980
total updates : 6392497
total queries : 57474984
total ops : 63867481
find throughput : 5747498
rq throughput : 0
update throughput : 639249
query throughput : 5747498
total throughput : 6386748
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=29.6975
PAPI_L3_TCM=2.19772
PAPI_TOT_CYC=18187.4
PAPI_TOT_INS=3616.35
garbage=28128438499688
total_execution_walltime=16.863s
REALTIME_MAIN_END_PERF_FORMAT=6998556.305752749
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=17.06, faults_major=0, faults_minor=291281, mem_maxresident_kb=1165988, user_cputime=774.14, sys_cputime=31.92, percent_cpu=4724%
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=2