__step=000105
__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=data000105.txt
__path_data=data/data000105.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995452.292980947
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.013s
prefilling_size=3890379 prefilling_elapsed_millis=1.001s
prefilling_size=4209239 prefilling_elapsed_millis=1.101s
prefilling_size=4526766 prefilling_elapsed_millis=1.201s
prefilling_size=4826949 prefilling_elapsed_millis=1.302s
prefilling_size=5111416 prefilling_elapsed_millis=1.402s
prefilling_size=5388456 prefilling_elapsed_millis=1.502s
prefilling_size=5635718 prefilling_elapsed_millis=1.602s
prefilling_size=5878370 prefilling_elapsed_millis=1.702s
prefilling_size=6110013 prefilling_elapsed_millis=1.803s
prefilling_size=6331226 prefilling_elapsed_millis=1.903s
prefilling_size=6539870 prefilling_elapsed_millis=2.003s
prefilling_size=6741693 prefilling_elapsed_millis=2.103s
prefilling_size=6932138 prefilling_elapsed_millis=2.203s
prefilling_size=7111956 prefilling_elapsed_millis=2.304s
prefilling_size=7283306 prefilling_elapsed_millis=2.404s
prefilling_size=7445915 prefilling_elapsed_millis=2.504s
prefilling_size=7601745 prefilling_elapsed_millis=2.604s
prefilling_size=7747162 prefilling_elapsed_millis=2.704s
prefilling_size=7884807 prefilling_elapsed_millis=2.805s
prefilling_size=8015764 prefilling_elapsed_millis=2.905s
prefilling_size=8139467 prefilling_elapsed_millis=3.005s
prefilling_size=8255992 prefilling_elapsed_millis=3.105s
prefilling_size=8365000 prefilling_elapsed_millis=3.205s
prefilling_size=8468641 prefilling_elapsed_millis=3.306s
prefilling_size=8565651 prefilling_elapsed_millis=3.406s
prefilling_size=8656484 prefilling_elapsed_millis=3.506s
prefilling_size=8743523 prefilling_elapsed_millis=3.606s
prefilling_size=8823918 prefilling_elapsed_millis=3.707s
prefilling_size=8898520 prefilling_elapsed_millis=3.807s
prefilling_size=8969652 prefilling_elapsed_millis=3.907s
prefilling_size=9036485 prefilling_elapsed_millis=4.007s
prefilling_size=9098960 prefilling_elapsed_millis=4.107s
prefilling_size=9156129 prefilling_elapsed_millis=4.208s
prefilling_size=9211314 prefilling_elapsed_millis=4.308s
prefilling_size=9262752 prefilling_elapsed_millis=4.408s
prefilling_size=9312113 prefilling_elapsed_millis=4.508s
prefilling_size=9356595 prefilling_elapsed_millis=4.608s
prefilling_size=9398500 prefilling_elapsed_millis=4.709s
prefilling_size=9437770 prefilling_elapsed_millis=4.809s
prefilling_size=9474450 prefilling_elapsed_millis=4.909s
prefilling_size=9509854 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 490103 keys with 48 threads...
tid=0 thread_prefill_amount=208300 percent_done_estimate=2040.1 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=212466 percent_done_estimate=2080.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=216632 percent_done_estimate=2121.7 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0849071s
prefilling_size=10000000 prefilling_elapsed_millis=0.085s
finished prefilling to size 10000000 for expected size 10000000 keysum=99988454220726, performing 62097898 updates; total_prefilling_elapsed_ms=85 ms)
pref_size=10000000
pref_millis=85
prefill_elapsed_ms=85
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6995457.688169953
REALTIME_END_PERF_FORMAT=6995467.688252208
###############################################################################
################################## 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 10 65 463 3499 26339 205238 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1618972
tree_stats_numNodesAtDepth=1 1 10 65 463 3499 26339 205238 1618972
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9991281
tree_stats_avgDegreeAtDepth=1 10 6.5 7.12308 7.55724 7.52758 7.79217 7.88827 6.17137
tree_stats_height=9
tree_stats_numInternals=235616
tree_stats_numLeaves=1618972
tree_stats_numNodes=1854588
tree_stats_numKeys=9991281
tree_stats_avgDegreeInternal=7.87123
tree_stats_avgDegreeLeaves=6.17137
tree_stats_avgDegree=6.38733
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=477550
first_thread_announced_epoch_by_thread=25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988 25988
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=225851 227769 229005 229949 229078 225891 225939 227904 229954 230305 227453 225861 226567 228406 230286 229212 229103 227458 224757 227189 228323 228725 227567 225786 225436 227443 228692 230612 228998 226890 226004 226512 229599 230432 228446 227310 225958 228064 229140 229677 230502 228059 225620 227133 228924 229204 227362 226113
sum_num_inserts_total=10940468
sum_num_deletes_by_thread=225237 226965 229192 229550 228887 226979 225766 226458 229612 230375 227564 225736 225705 228243 229735 229779 228713 227506 225057 227015 228459 228235 227590 225908 226754 228116 228311 229931 229465 226500 226398 227913 229545 230082 227990 227605 227279 228391 229415 230778 229845 226539 225624 227283 228659 229576 228980 226075
sum_num_deletes_total=10941320
sum_num_searches_by_thread=4051613 4090711 4120257 4138641 4119999 4075196 4076112 4089192 4142312 4153925 4101957 4067583 4080847 4110040 4131366 4131719 4124885 4095285 4053280 4088530 4111347 4129713 4095047 4070851 4067249 4096922 4120453 4130789 4122006 4083567 4076227 4093488 4134295 4158206 4113203 4088426 4086513 4111383 4124485 4140514 4131583 4094786 4062536 4081356 4110607 4127612 4104737 4075185
sum_num_searches_total=196986536
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4502701 4545445 4578454 4598140 4577964 4528066 4527817 4543554 4601878 4614605 4556974 4519180 4533119 4566689 4591387 4590710 4582701 4550249 4503094 4542734 4568129 4586673 4550204 4522545 4519439 4552481 4577456 4591332 4580469 4536957 4528629 4547913 4593439 4618720 4569639 4543341 4539750 4567838 4583040 4600969 4591930 4549384 4513780 4535772 4568190 4586392 4561079 4527373
average_num_operations_total=4559756
stdev_num_operations_total=4351
sum_num_operations_total=218868324
min_num_operations_total=4502701
max_num_operations_total=4618720
first_time_thread_terminate_by_thread=10000139 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082
min_time_thread_terminate_total=10000082
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 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=99910219327520
threads_final_size=9991281
final_keysum=99910219327520
final_size=9991281
validate_result=success
Validation OK.
Structural validation OK.
total_find=196986536
total_rq=0
total_inserts=10940468
total_deletes=10941320
total_updates=21881788
total_queries=196986536
total_ops=218868324
find_throughput=19698653
rq_throughput=0
update_throughput=2188178
query_throughput=19698653
total_throughput=21886832
total find : 196986536
total rq : 0
total inserts : 10940468
total deletes : 10941320
total updates : 21881788
total queries : 196986536
total ops : 218868324
find throughput : 19698653
rq throughput : 0
update throughput : 2188178
query throughput : 19698653
total throughput : 21886832
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=33.4856
PAPI_L3_TCM=6.36592
PAPI_TOT_CYC=4552.96
PAPI_TOT_INS=2415.66
garbage=985673965747901
total_execution_walltime=15.567s
REALTIME_MAIN_END_PERF_FORMAT=6995467.861434984
TL2 system shutdown:
GCLOCK=0x36E925A Starts=287008406 Aborts=6980362
Overflows: R=0 W=5027 L=0
[time_cmd_output] time_elapsed_sec=15.82, faults_major=0, faults_minor=784782, mem_maxresident_kb=2108816, user_cputime=676.12, sys_cputime=64.88, percent_cpu=4681%
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=1