__step=000106
__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.tl2 -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=data000106.txt
__path_data=data/data000106.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995468.191042673
binary=./brown_abtree_tm_auto.debra.tl2
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=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=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
TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.009s
prefilling_size=3898361 prefilling_elapsed_millis=1.001s
prefilling_size=4218337 prefilling_elapsed_millis=1.101s
prefilling_size=4522117 prefilling_elapsed_millis=1.201s
prefilling_size=4813406 prefilling_elapsed_millis=1.301s
prefilling_size=5082204 prefilling_elapsed_millis=1.401s
prefilling_size=5351164 prefilling_elapsed_millis=1.501s
prefilling_size=5605442 prefilling_elapsed_millis=1.602s
prefilling_size=5845959 prefilling_elapsed_millis=1.702s
prefilling_size=6077406 prefilling_elapsed_millis=1.802s
prefilling_size=6297261 prefilling_elapsed_millis=1.902s
prefilling_size=6506195 prefilling_elapsed_millis=2.002s
prefilling_size=6704877 prefilling_elapsed_millis=2.103s
prefilling_size=6896562 prefilling_elapsed_millis=2.203s
prefilling_size=7077425 prefilling_elapsed_millis=2.303s
prefilling_size=7250406 prefilling_elapsed_millis=2.403s
prefilling_size=7413276 prefilling_elapsed_millis=2.504s
prefilling_size=7568186 prefilling_elapsed_millis=2.604s
prefilling_size=7715126 prefilling_elapsed_millis=2.704s
prefilling_size=7851629 prefilling_elapsed_millis=2.804s
prefilling_size=7983959 prefilling_elapsed_millis=2.904s
prefilling_size=8106405 prefilling_elapsed_millis=3.005s
prefilling_size=8222939 prefilling_elapsed_millis=3.105s
prefilling_size=8332351 prefilling_elapsed_millis=3.205s
prefilling_size=8435714 prefilling_elapsed_millis=3.305s
prefilling_size=8532649 prefilling_elapsed_millis=3.405s
prefilling_size=8625155 prefilling_elapsed_millis=3.506s
prefilling_size=8711333 prefilling_elapsed_millis=3.606s
prefilling_size=8792076 prefilling_elapsed_millis=3.706s
prefilling_size=8867939 prefilling_elapsed_millis=3.806s
prefilling_size=8939400 prefilling_elapsed_millis=3.906s
prefilling_size=9006216 prefilling_elapsed_millis=4.007s
prefilling_size=9070041 prefilling_elapsed_millis=4.107s
prefilling_size=9129902 prefilling_elapsed_millis=4.207s
prefilling_size=9185665 prefilling_elapsed_millis=4.307s
prefilling_size=9238246 prefilling_elapsed_millis=4.407s
prefilling_size=9287153 prefilling_elapsed_millis=4.508s
prefilling_size=9332898 prefilling_elapsed_millis=4.608s
prefilling_size=9374889 prefilling_elapsed_millis=4.708s
prefilling_size=9416116 prefilling_elapsed_millis=4.808s
prefilling_size=9454184 prefilling_elapsed_millis=4.908s
prefilling_size=9489506 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 510480 keys with 48 threads...
tid=0 thread_prefill_amount=179138 percent_done_estimate=1684.4 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=183304 percent_done_estimate=1723.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=187470 percent_done_estimate=1762.8 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0877794s
prefilling_size=10000000 prefilling_elapsed_millis=0.088s
finished prefilling to size 10000000 for expected size 10000000 keysum=99972997750822, performing 62160770 updates; total_prefilling_elapsed_ms=88 ms)
pref_size=10000000
pref_millis=88
prefill_elapsed_ms=88
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6995473.576159288
REALTIME_END_PERF_FORMAT=6995483.576238349
###############################################################################
################################## 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 463 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.015s
tree_stats_numInternalsAtDepth=1 1 9 65 463 3459 26234 205425 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1619547
tree_stats_numNodesAtDepth=1 1 9 65 463 3459 26234 205425 1619547
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001446
tree_stats_avgDegreeAtDepth=1 9 7.22222 7.12308 7.47084 7.58427 7.83049 7.88388 6.17546
tree_stats_height=9
tree_stats_numInternals=235657
tree_stats_numLeaves=1619547
tree_stats_numNodes=1855204
tree_stats_numKeys=10001446
tree_stats_avgDegreeInternal=7.87248
tree_stats_avgDegreeLeaves=6.17546
tree_stats_avgDegree=6.39102
tree_stats_avgKeyDepth=8
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=479035
first_thread_announced_epoch_by_thread=26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096 26096
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=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
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=
sum_stmprefix_commit_total=
sum_slow_commit_total=
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=226076 228186 228923 228632 229285 226077 227531 226908 230117 231148 227855 226356 226508 228814 229259 230413 228020 227270 225786 227338 228996 229901 227738 225059 227268 227771 229213 230020 228745 226167 226420 228030 230060 231740 228397 226483 226898 229492 230250 230433 229271 226956 226071 228304 228903 230137 228008 226180
sum_num_inserts_total=10953413
sum_num_deletes_by_thread=226296 227847 230102 228833 229844 225857 226259 227930 230457 229750 228529 226324 227291 228411 230843 230721 228538 226840 226558 227290 229507 230151 228136 225418 226640 226974 228156 230013 228523 226684 226987 228565 230195 231098 228022 227163 227630 228776 230276 230363 229555 227252 224917 227411 229151 229479 227285 226301
sum_num_deletes_total=10955148
sum_num_searches_by_thread=4068654 4097703 4124427 4130102 4127484 4067520 4079538 4095591 4141922 4155602 4114313 4077431 4083913 4116806 4144426 4144990 4124783 4091256 4060357 4090507 4117091 4137394 4102577 4068490 4079120 4101607 4128374 4142901 4128605 4081095 4082293 4105695 4142464 4156972 4107860 4083203 4093404 4115304 4141991 4144662 4129992 4082580 4060147 4098411 4120997 4139846 4104046 4071857
sum_num_searches_total=197206303
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4521026 4553736 4583452 4587567 4586613 4519454 4533328 4550429 4602496 4616500 4570697 4530111 4537712 4574031 4604528 4606124 4581341 4545366 4512701 4545135 4575594 4597446 4558451 4518967 4533028 4556352 4585743 4602934 4585873 4533946 4535700 4562290 4602719 4619810 4564279 4536849 4547932 4573572 4602517 4605458 4588818 4536788 4511135 4554126 4579051 4599462 4559339 4524338
average_num_operations_total=4564893
stdev_num_operations_total=4487
sum_num_operations_total=219114864
min_num_operations_total=4511135
max_num_operations_total=4619810
first_time_thread_terminate_by_thread=10000131 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000078 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000131
first_time_thread_start_by_thread=10 6 5 5 5 6 5 6 5 5 5 5 6 6 5 6 6 5 5 6 6 6 5 5 4 6 5 5 5 6 5 6 5 5 5 5 6 6 5 6 6 5 5 6 6 6 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=99995409788247
threads_final_size=10001446
final_keysum=99995409788247
final_size=10001446
validate_result=success
Validation OK.
Structural validation OK.
total_find=197206303
total_rq=0
total_inserts=10953413
total_deletes=10955148
total_updates=21908561
total_queries=197206303
total_ops=219114864
find_throughput=19720630
rq_throughput=0
update_throughput=2190856
query_throughput=19720630
total_throughput=21911486
total find : 197206303
total rq : 0
total inserts : 10953413
total deletes : 10955148
total updates : 21908561
total queries : 197206303
total ops : 219114864
find throughput : 19720630
rq throughput : 0
update throughput : 2190856
query throughput : 19720630
total throughput : 21911486
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=33.5378
PAPI_L3_TCM=6.36747
PAPI_TOT_CYC=4548.04
PAPI_TOT_INS=2426.6
garbage=943923462654667
total_execution_walltime=15.555s
REALTIME_MAIN_END_PERF_FORMAT=6995483.748790396
TL2 system shutdown:
GCLOCK=0x3757E24 Starts=287133052 Aborts=6832320
Overflows: R=0 W=4871 L=0
[time_cmd_output] time_elapsed_sec=15.82, faults_major=0, faults_minor=781275, mem_maxresident_kb=2099984, user_cputime=676.05, sys_cputime=66.43, percent_cpu=4691%
timeout=false
algorithm=brown_abtree_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2