__step=000042
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000042.txt
__path_data=data/data000042.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=220263.582101932
binary=./brown_abtree_tm_auto.debra.tl2
parsed custom binding: 0-63,128-191,64-127,192-255
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.023s
prefilling_size=3543032 prefilling_elapsed_millis=1s
prefilling_size=3850465 prefilling_elapsed_millis=1.1s
prefilling_size=4145271 prefilling_elapsed_millis=1.201s
prefilling_size=4431376 prefilling_elapsed_millis=1.301s
prefilling_size=4700276 prefilling_elapsed_millis=1.401s
prefilling_size=4954695 prefilling_elapsed_millis=1.501s
prefilling_size=5202275 prefilling_elapsed_millis=1.601s
prefilling_size=5442470 prefilling_elapsed_millis=1.701s
prefilling_size=5671267 prefilling_elapsed_millis=1.801s
prefilling_size=5892521 prefilling_elapsed_millis=1.901s
prefilling_size=6103735 prefilling_elapsed_millis=2.002s
prefilling_size=6306536 prefilling_elapsed_millis=2.102s
prefilling_size=6498609 prefilling_elapsed_millis=2.202s
prefilling_size=6683994 prefilling_elapsed_millis=2.302s
prefilling_size=6862002 prefilling_elapsed_millis=2.402s
prefilling_size=7030674 prefilling_elapsed_millis=2.502s
prefilling_size=7190362 prefilling_elapsed_millis=2.602s
prefilling_size=7341985 prefilling_elapsed_millis=2.702s
prefilling_size=7487521 prefilling_elapsed_millis=2.803s
prefilling_size=7626237 prefilling_elapsed_millis=2.903s
prefilling_size=7756323 prefilling_elapsed_millis=3.003s
prefilling_size=7882025 prefilling_elapsed_millis=3.103s
prefilling_size=7999939 prefilling_elapsed_millis=3.204s
prefilling_size=8111683 prefilling_elapsed_millis=3.304s
prefilling_size=8216303 prefilling_elapsed_millis=3.404s
prefilling_size=8318274 prefilling_elapsed_millis=3.504s
prefilling_size=8414680 prefilling_elapsed_millis=3.604s
prefilling_size=8504458 prefilling_elapsed_millis=3.704s
prefilling_size=8588962 prefilling_elapsed_millis=3.804s
prefilling_size=8669801 prefilling_elapsed_millis=3.904s
prefilling_size=8747158 prefilling_elapsed_millis=4.005s
prefilling_size=8820607 prefilling_elapsed_millis=4.105s
prefilling_size=8888396 prefilling_elapsed_millis=4.205s
prefilling_size=8952785 prefilling_elapsed_millis=4.305s
prefilling_size=9012719 prefilling_elapsed_millis=4.405s
prefilling_size=9070555 prefilling_elapsed_millis=4.505s
prefilling_size=9124074 prefilling_elapsed_millis=4.605s
prefilling_size=9177185 prefilling_elapsed_millis=4.705s
prefilling_size=9225319 prefilling_elapsed_millis=4.806s
prefilling_size=9271628 prefilling_elapsed_millis=4.906s
prefilling_size=9315198 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 684780 keys with 64 threads...
tid=0 thread_prefill_amount=131250 percent_done_estimate=1226.7 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=134375 percent_done_estimate=1255.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=137500 percent_done_estimate=1285.1 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=140625 percent_done_estimate=1314.3 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.117449s
prefilling_size=10000000 prefilling_elapsed_millis=0.123s
finished prefilling to size 10000000 for expected size 10000000 keysum=99986047652809, performing 56823164 updates; total_prefilling_elapsed_ms=123 ms)
pref_size=10000000
pref_millis=123
prefill_elapsed_ms=123
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=220268.870877490
REALTIME_END_PERF_FORMAT=220278.870947355
###############################################################################
################################## 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 (64 threads)...
partitioned into 482 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.037s
tree_stats_numInternalsAtDepth=1 1 8 61 482 3579 26929 209281 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1651979
tree_stats_numNodesAtDepth=1 1 8 61 482 3579 26929 209281 1651979
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9983213
tree_stats_avgDegreeAtDepth=1 8 7.625 7.90164 7.42531 7.52417 7.77158 7.89359 6.04318
tree_stats_height=9
tree_stats_numInternals=240342
tree_stats_numLeaves=1651979
tree_stats_numNodes=1892321
tree_stats_numKeys=9983213
tree_stats_avgDegreeInternal=7.87345
tree_stats_avgDegreeLeaves=6.04318
tree_stats_avgDegree=6.27564
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=565417
first_thread_announced_epoch_by_thread=20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760 20760
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=269708 270194 268410 269005 269250 269584 270658 269629 270055 271354 270182 270205 269812 269069 267265 267006 267254 267391 267391 266159 267092 269657 267949 268144 268943 268164 270264 268146 267801 266263 266317 265933 268348 268192 267921 270589 270641 269632 270568 269647 270157 270419 267898 265588 266655 266204 265939 266665 265583 270215 268469 269219 269501 269105 269478 269113 267477 262124 266379 266022 267514 268036 268766 270586
sum_num_inserts_total=17176904
sum_num_deletes_by_thread=270604 270256 269127 267646 268928 267996 270045 270881 269525 269984 269450 270589 270569 268545 266711 266974 266922 267824 267445 267101 266652 269945 268265 268744 268854 270222 269054 267738 266885 267035 265512 266027 266811 267320 267154 270065 269560 270118 269845 269911 270470 269356 268180 266844 266574 266711 266783 266222 266474 269271 268660 269272 269069 269083 269035 268537 266630 262789 266754 266913 268402 269182 268674 270022
sum_num_deletes_total=17172746
sum_num_searches_by_thread=4868472 4865118 4839278 4823862 4840633 4838290 4878052 4863308 4864115 4863914 4850681 4853504 4856955 4829966 4808123 4809333 4802060 4812828 4804286 4803157 4803473 4863089 4844422 4842445 4841202 4849346 4840478 4839278 4819543 4804693 4796702 4787384 4814451 4814322 4812831 4871512 4863609 4855623 4855412 4864772 4862721 4863331 4820763 4802096 4800871 4800259 4792755 4785915 4790967 4844905 4837878 4835594 4846197 4846667 4847445 4843092 4809054 4724861 4798229 4798033 4822143 4823785 4842177 4873912
sum_num_searches_total=309174172
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5408784 5405568 5376815 5360513 5378811 5375870 5418755 5403818 5403695 5405252 5390313 5394298 5397336 5367580 5342099 5343313 5336236 5348043 5339122 5336417 5337217 5402691 5380636 5379333 5378999 5387732 5379796 5375162 5354229 5337991 5328531 5319344 5349610 5349834 5347906 5412166 5403810 5395373 5395825 5404330 5403348 5403106 5356841 5334528 5334100 5333174 5325477 5318802 5323024 5384391 5375007 5374085 5384767 5384855 5385958 5380742 5343161 5249774 5331362 5330968 5358059 5361003 5379617 5414520
average_num_operations_total=5367559
stdev_num_operations_total=4055
sum_num_operations_total=343523822
min_num_operations_total=5249774
max_num_operations_total=5418755
first_time_thread_terminate_by_thread=10000110 10000068 10000068 10000068 10000071 10000071 10000077 10000071 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000069 10000069 10000069 10000069 10000073 10000073 10000073 10000073 10000070 10000070 10000070 10000070 10000074 10000074 10000074 10000074 10000071 10000071 10000071 10000071 10000071 10000070 10000071 10000070 10000069 10000069 10000069 10000069 10000071 10000072 10000071 10000072 10000072 10000072 10000072 10000072 10000070 10000070 10000070 10000070 10000069 10000069 10000069 10000069 10000072 10000072 10000072 10000072
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000110
first_time_thread_start_by_thread=7 2 2 2 9 9 9 9 7 7 7 7 4 5 5 4 5 5 5 5 9 9 9 9 11 11 11 11 10 10 10 10 3 3 3 3 7 6 6 6 8 8 8 8 4 4 4 4 5 5 5 5 12 11 12 12 4 4 4 4 6 6 6 6
min_time_thread_start_total=2
max_time_thread_start_total=12
sum_duration_all_ops_total=
threads_final_keysum=99827434447354
threads_final_size=9983213
final_keysum=99827434447354
final_size=9983213
validate_result=success
Validation OK.
Structural validation OK.
total_find=309174172
total_rq=0
total_inserts=17176904
total_deletes=17172746
total_updates=34349650
total_queries=309174172
total_ops=343523822
find_throughput=30917417
rq_throughput=0
update_throughput=3434965
query_throughput=30917417
total_throughput=34352382
total find : 309174172
total rq : 0
total inserts : 17176904
total deletes : 17172746
total updates : 34349650
total queries : 309174172
total ops : 343523822
find throughput : 30917417
rq throughput : 0
update throughput : 3434965
query throughput : 30917417
total throughput : 34352382
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=1305807473683449
total_execution_walltime=15.482s
REALTIME_MAIN_END_PERF_FORMAT=220279.64907123
TL2 system shutdown:
GCLOCK=0x2339A76 Starts=406759693 Aborts=7715688
Overflows: R=0 W=6179 L=0
[time_cmd_output] time_elapsed_sec=15.71, faults_major=0, faults_minor=788359, mem_maxresident_kb=2112472, user_cputime=933.29, sys_cputime=49.28, percent_cpu=6252%
timeout=false
algorithm=brown_abtree_tm_auto.debra.tl2
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2