__step=000025
__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=data000025.txt
__path_data=data/data000025.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207066.560057635
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.02s
prefilling_size=4821059 prefilling_elapsed_millis=1s
prefilling_size=5153160 prefilling_elapsed_millis=1.1s
prefilling_size=5455454 prefilling_elapsed_millis=1.201s
prefilling_size=5741000 prefilling_elapsed_millis=1.301s
prefilling_size=6013510 prefilling_elapsed_millis=1.401s
prefilling_size=6260588 prefilling_elapsed_millis=1.501s
prefilling_size=6495518 prefilling_elapsed_millis=1.601s
prefilling_size=6717477 prefilling_elapsed_millis=1.701s
prefilling_size=6927486 prefilling_elapsed_millis=1.801s
prefilling_size=7119860 prefilling_elapsed_millis=1.901s
prefilling_size=7301124 prefilling_elapsed_millis=2.002s
prefilling_size=7472133 prefilling_elapsed_millis=2.102s
prefilling_size=7629574 prefilling_elapsed_millis=2.202s
prefilling_size=7777848 prefilling_elapsed_millis=2.302s
prefilling_size=7919462 prefilling_elapsed_millis=2.402s
prefilling_size=8047851 prefilling_elapsed_millis=2.502s
prefilling_size=8169205 prefilling_elapsed_millis=2.602s
prefilling_size=8284575 prefilling_elapsed_millis=2.702s
prefilling_size=8393914 prefilling_elapsed_millis=2.802s
prefilling_size=8493048 prefilling_elapsed_millis=2.903s
prefilling_size=8586574 prefilling_elapsed_millis=3.003s
prefilling_size=8675655 prefilling_elapsed_millis=3.103s
prefilling_size=8756032 prefilling_elapsed_millis=3.204s
prefilling_size=8833239 prefilling_elapsed_millis=3.304s
prefilling_size=8908083 prefilling_elapsed_millis=3.404s
prefilling_size=8976771 prefilling_elapsed_millis=3.504s
prefilling_size=9039280 prefilling_elapsed_millis=3.604s
prefilling_size=9098103 prefilling_elapsed_millis=3.704s
prefilling_size=9152756 prefilling_elapsed_millis=3.804s
prefilling_size=9208149 prefilling_elapsed_millis=3.904s
prefilling_size=9258816 prefilling_elapsed_millis=4.004s
prefilling_size=9302647 prefilling_elapsed_millis=4.105s
prefilling_size=9346879 prefilling_elapsed_millis=4.205s
prefilling_size=9387374 prefilling_elapsed_millis=4.305s
prefilling_size=9424851 prefilling_elapsed_millis=4.405s
prefilling_size=9459128 prefilling_elapsed_millis=4.505s
prefilling_size=9490318 prefilling_elapsed_millis=4.605s
prefilling_size=9522712 prefilling_elapsed_millis=4.705s
prefilling_size=9553237 prefilling_elapsed_millis=4.805s
prefilling_size=9581089 prefilling_elapsed_millis=4.905s
prefilling_size=9607629 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 392376 keys with 64 threads...
tid=0 thread_prefill_amount=165625 percent_done_estimate=2701.5 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=168750 percent_done_estimate=2752.5 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0374518s
prefilling_size=10000000 prefilling_elapsed_millis=0.039s
finished prefilling to size 10000000 for expected size 10000000 keysum=100007685565218, performing 66453595 updates; total_prefilling_elapsed_ms=39 ms)
pref_size=10000000
pref_millis=39
prefill_elapsed_ms=39
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207071.754874520
REALTIME_END_PERF_FORMAT=207081.754950924

###############################################################################
################################## 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.257s

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10002035
tree_stats_avgDegreeAtDepth=1.0002e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.0002e+07
tree_stats_avgDegree=1.0002e+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=45121 44804 44742 44859 44917 45037 44811 44840 44888 45041 44520 44776 44496 44946 44918 44785 45267 45336 44968 45197 45300 44939 45134 45213 44879 45241 45081 44562 45080 45228 44629 45310 45889 45674 45704 45547 45514 45753 45979 45618 45307 45215 45342 45545 45264 45579 45483 45293 45459 45318 45101 45188 44878 45300 45155 44895 45332 45369 45275 45204 45013 44990 45612 45092
sum_num_inserts_total=2890752
sum_num_deletes_by_thread=44712 44796 44814 44476 44840 44920 44834 45199 44785 44652 44639 44486 44669 44747 44992 44810 45271 45265 45132 45416 44942 44733 45266 44844 45170 45094 44545 44929 44793 45004 45079 45068 46159 45915 45593 45628 45438 45822 45799 45360 45910 45161 45635 45668 45618 45669 45290 45390 45229 45974 45180 45031 45002 45209 45167 44915 44696 45490 44730 45404 44999 45056 45292 45061
sum_num_deletes_total=2889412
sum_num_searches_by_thread=8877787 8873698 8876499 8850404 8901124 8891565 8880852 8888715 8852558 8852980 8845470 8848861 8868443 8858522 8842818 8846695 8965517 8973335 8947433 8947167 8953128 8958917 8941550 8934827 8912252 8929292 8913951 8896924 8912900 8922115 8915950 8907743 9068203 9061562 9049609 9045571 9062316 9058806 9046498 9044117 9020839 9013100 8993077 8995362 9014117 9012817 9002968 8983741 8951235 8956616 8931418 8932545 8967687 8965349 8947088 8943964 8951653 8955129 8948659 8945818 8968051 8954405 8942105 8942246
sum_num_searches_total=572466663
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=8967620 8963298 8966055 8939739 8990881 8981522 8970497 8978754 8942231 8942673 8934629 8938123 8957608 8948215 8932728 8936290 9056055 9063936 9037533 9037780 9043370 9048589 9031950 9024884 9002301 9019627 9003577 8986415 9002773 9012347 9005658 8998121 9160251 9153151 9140906 9136746 9153268 9150381 9138276 9135095 9112056 9103476 9084054 9086575 9104999 9104065 9093741 9074424 9041923 9047908 9021699 9022764 9057567 9055858 9037410 9033774 9041681 9045988 9038664 9036426 9058063 9044451 9033009 9032399
average_num_operations_total=9035106
stdev_num_operations_total=7903
sum_num_operations_total=578246827
min_num_operations_total=8932728
max_num_operations_total=9160251
first_time_thread_terminate_by_thread=10000124 10000075 10000075 10000075 10000078 10000078 10000078 10000078 10000080 10000080 10000080 10000080 10000077 10000076 10000076 10000077 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000078 10000078 10000078 10000078 10000079 10000079 10000079 10000079 10000080 10000080 10000080 10000080 10000079 10000079 10000079 10000079 10000078 10000078 10000078 10000078 10000076 10000076 10000076 10000076 10000080 10000080 10000080 10000080 10000076 10000075 10000076 10000075 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077
min_time_thread_terminate_total=10000075
max_time_thread_terminate_total=10000124
first_time_thread_start_by_thread=9 3 3 3 4 4 4 4 7 7 7 7 9 9 9 9 8 8 8 8 7 7 7 7 9 9 9 9 8 8 8 8 4 4 4 4 5 5 5 5 5 5 5 5 5 5 5 5 6 6 6 6 6 6 6 6 7 7 6 7 6 6 6 6
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100040955030320
threads_final_size=10002035
final_keysum=100040955030320
final_size=10002035
validate_result=success
Validation OK.
Structural validation OK.

total_find=572466663
total_rq=0
total_inserts=2890752
total_deletes=2889412
total_updates=5780164
total_queries=572466663
total_ops=578246827
find_throughput=57246666
rq_throughput=0
update_throughput=578016
query_throughput=57246666
total_throughput=57824682


total find                    : 572466663
total rq                      : 0
total inserts                 : 2890752
total deletes                 : 2889412
total updates                 : 5780164
total queries                 : 572466663
total ops                     : 578246827
find throughput               : 57246666
rq throughput                 : 0
update throughput             : 578016
query throughput              : 57246666
total throughput              : 57824682

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=355706616616601
total_execution_walltime=15.553s
REALTIME_MAIN_END_PERF_FORMAT=207082.114173211
[time_cmd_output] time_elapsed_sec=15.72, faults_major=1, faults_minor=289272, mem_maxresident_kb=1149312, user_cputime=968.39, sys_cputime=4.61, percent_cpu=6186%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1