__step=000026
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./wang_openbwtree -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000026.txt
__path_data=data/data000026.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207082.348249323
binary=./wang_openbwtree
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=wang_openbwtree
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=0.5
DEL_FRAC=0.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=0.5 0.5
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.014s
prefilling_size=3907850 prefilling_elapsed_millis=1s
prefilling_size=4198036 prefilling_elapsed_millis=1.101s
prefilling_size=4472322 prefilling_elapsed_millis=1.201s
prefilling_size=4733579 prefilling_elapsed_millis=1.301s
prefilling_size=4982766 prefilling_elapsed_millis=1.401s
prefilling_size=5220282 prefilling_elapsed_millis=1.501s
prefilling_size=5444347 prefilling_elapsed_millis=1.601s
prefilling_size=5659365 prefilling_elapsed_millis=1.701s
prefilling_size=5863666 prefilling_elapsed_millis=1.801s
prefilling_size=6061122 prefilling_elapsed_millis=1.902s
prefilling_size=6245731 prefilling_elapsed_millis=2.002s
prefilling_size=6422850 prefilling_elapsed_millis=2.102s
prefilling_size=6590706 prefilling_elapsed_millis=2.202s
prefilling_size=6750617 prefilling_elapsed_millis=2.302s
prefilling_size=6904169 prefilling_elapsed_millis=2.402s
prefilling_size=7049961 prefilling_elapsed_millis=2.502s
prefilling_size=7189588 prefilling_elapsed_millis=2.602s
prefilling_size=7323277 prefilling_elapsed_millis=2.703s
prefilling_size=7448820 prefilling_elapsed_millis=2.803s
prefilling_size=7568770 prefilling_elapsed_millis=2.903s
prefilling_size=7683750 prefilling_elapsed_millis=3.003s
prefilling_size=7793119 prefilling_elapsed_millis=3.104s
prefilling_size=7895221 prefilling_elapsed_millis=3.204s
prefilling_size=7993030 prefilling_elapsed_millis=3.304s
prefilling_size=8086856 prefilling_elapsed_millis=3.404s
prefilling_size=8174714 prefilling_elapsed_millis=3.504s
prefilling_size=8259585 prefilling_elapsed_millis=3.604s
prefilling_size=8340623 prefilling_elapsed_millis=3.704s
prefilling_size=8417969 prefilling_elapsed_millis=3.805s
prefilling_size=8492194 prefilling_elapsed_millis=3.905s
prefilling_size=8562238 prefilling_elapsed_millis=4.005s
prefilling_size=8627131 prefilling_elapsed_millis=4.105s
prefilling_size=8691287 prefilling_elapsed_millis=4.205s
prefilling_size=8752043 prefilling_elapsed_millis=4.305s
prefilling_size=8810816 prefilling_elapsed_millis=4.405s
prefilling_size=8866449 prefilling_elapsed_millis=4.505s
prefilling_size=8919046 prefilling_elapsed_millis=4.606s
prefilling_size=8968963 prefilling_elapsed_millis=4.706s
prefilling_size=9017374 prefilling_elapsed_millis=4.806s
prefilling_size=9062540 prefilling_elapsed_millis=4.906s
prefilling_size=9105733 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 894256 keys with 64 threads...
tid=0 thread_prefill_amount=143750 percent_done_estimate=1028.8 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=146875 percent_done_estimate=1051.2 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=150000 percent_done_estimate=1073.5 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=153125 percent_done_estimate=1095.9 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.108288s
prefilling_size=10000000 prefilling_elapsed_millis=0.113s
finished prefilling to size 10000000 for expected size 10000000 keysum=99995938929713, performing 50008321 updates; total_prefilling_elapsed_ms=113 ms)
pref_size=10000000
pref_millis=113
prefill_elapsed_ms=113
main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=207087.610878700
REALTIME_END_PERF_FORMAT=207097.610951539

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
Open BwTree: createTreeStats iterating over all kv-pairs...
Open BwTree: createTreeStats finished iterating.

tree_stats_computeWalltime=0.258s

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10002529
tree_stats_avgDegreeAtDepth=1.00025e+07

tree_stats_height=1
tree_stats_numInternals=0
tree_stats_numLeaves=1
tree_stats_numNodes=1
tree_stats_numKeys=10002529

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00025e+07
tree_stats_avgDegree=1.00025e+07
tree_stats_avgKeyDepth=0


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=
first_thread_announced_epoch_by_thread=
sum_num_inserts_by_thread=42392 42593 41884 41608 42275 42426 42224 42390 42121 42054 41896 42135 42313 42063 41847 42038 41715 42105 41656 41781 42331 42271 42514 42234 41764 42157 42182 41476 41628 41905 41802 41991 42382 42275 42050 42324 42368 42675 41764 41993 42141 42057 42136 42024 41959 42233 41815 42053 41946 41590 41518 41510 41737 41691 41649 42004 41860 42126 42026 42049 41913 42221 42026 41814
sum_num_inserts_total=2689700
sum_num_deletes_by_thread=42335 42382 42308 41822 42309 42632 42046 42251 42224 42004 41778 41954 41825 41743 42027 42043 42101 42186 41835 41989 42554 41904 42266 41873 42174 41532 41587 41491 42008 42110 41874 41916 41919 42181 42151 42315 41957 42504 41906 42238 42082 42097 42296 41685 42175 41877 41473 42092 41449 41726 41475 41932 41623 41792 41315 41907 41745 41413 41924 41588 41948 41872 41885 42131
sum_num_deletes_total=2685756
sum_num_searches_by_thread=8352091 8375817 8355184 8330297 8370195 8370758 8362190 8350227 8322626 8326946 8307555 8291135 8323229 8312545 8309436 8306992 8325026 8331239 8325399 8317971 8335747 8336073 8325068 8323629 8281269 8256567 8287396 8270695 8298355 8302429 8291198 8291623 8359849 8360381 8339471 8349595 8356063 8356455 8345857 8342160 8320089 8324343 8301678 8302665 8314827 8313398 8312401 8299056 8274278 8277125 8264279 8262617 8261318 8266751 8263312 8256158 8254340 8260737 8270066 8257848 8278219 8273738 8263613 8255961
sum_num_searches_total=531805555
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=8436818 8460792 8439376 8413727 8454779 8455816 8446460 8434868 8406971 8411004 8391229 8375224 8407367 8396351 8393310 8391073 8408842 8415530 8408890 8401741 8420632 8420248 8409848 8407736 8365207 8340256 8371165 8353662 8381991 8386444 8374874 8375530 8444150 8444837 8423672 8434234 8440388 8441634 8429527 8426391 8404312 8408497 8386110 8386374 8398961 8397508 8395689 8383201 8357673 8360441 8347272 8346059 8344678 8350234 8346276 8340069 8337945 8344276 8354016 8341485 8362080 8357831 8347524 8339906
average_num_operations_total=8393453
stdev_num_operations_total=4490
sum_num_operations_total=537181011
min_num_operations_total=8337945
max_num_operations_total=8460792
first_time_thread_terminate_by_thread=10000119 10000073 10000072 10000073 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000073 10000072 10000072 10000072 10000072 10000076 10000076 10000076 10000076 10000074 10000074 10000074 10000074 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000075 10000075 10000075 10000075 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000077 10000077 10000077 10000077
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000119
first_time_thread_start_by_thread=9 4 4 4 5 5 5 5 6 6 6 6 5 5 5 5 9 9 9 9 6 6 6 6 10 10 10 10 7 7 7 7 8 8 8 8 11 11 11 11 8 8 8 8 5 5 5 5 10 10 10 10 7 7 7 7 10 10 10 10 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=100012368599266
threads_final_size=10002529
final_keysum=100012368599266
final_size=10002529
validate_result=success
Validation OK.
Structural validation OK.

total_find=531805555
total_rq=0
total_inserts=2689700
total_deletes=2685756
total_updates=5375456
total_queries=531805555
total_ops=537181011
find_throughput=53180555
rq_throughput=0
update_throughput=537545
query_throughput=53180555
total_throughput=53718101


total find                    : 531805555
total rq                      : 0
total inserts                 : 2689700
total deletes                 : 2685756
total updates                 : 5375456
total queries                 : 531805555
total ops                     : 537181011
find throughput               : 53180555
rq throughput                 : 0
update throughput             : 537545
query throughput              : 53180555
total throughput              : 53718101

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=416516295090479
total_execution_walltime=15.626s
REALTIME_MAIN_END_PERF_FORMAT=207097.975396688
[time_cmd_output] time_elapsed_sec=15.79, faults_major=0, faults_minor=283577, mem_maxresident_kb=1130484, user_cputime=973.20, sys_cputime=4.80, percent_cpu=6193%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2