__step=000362
__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 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000362.txt
__path_data=data/data000362.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211140.954464354
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=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
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.022s
prefilling_size=3901686 prefilling_elapsed_millis=1s
prefilling_size=4195148 prefilling_elapsed_millis=1.101s
prefilling_size=4473667 prefilling_elapsed_millis=1.201s
prefilling_size=4741536 prefilling_elapsed_millis=1.301s
prefilling_size=4991463 prefilling_elapsed_millis=1.401s
prefilling_size=5229197 prefilling_elapsed_millis=1.502s
prefilling_size=5454097 prefilling_elapsed_millis=1.602s
prefilling_size=5666962 prefilling_elapsed_millis=1.702s
prefilling_size=5871920 prefilling_elapsed_millis=1.802s
prefilling_size=6065637 prefilling_elapsed_millis=1.903s
prefilling_size=6251052 prefilling_elapsed_millis=2.003s
prefilling_size=6426801 prefilling_elapsed_millis=2.103s
prefilling_size=6595198 prefilling_elapsed_millis=2.203s
prefilling_size=6756834 prefilling_elapsed_millis=2.304s
prefilling_size=6908227 prefilling_elapsed_millis=2.404s
prefilling_size=7053075 prefilling_elapsed_millis=2.504s
prefilling_size=7192119 prefilling_elapsed_millis=2.604s
prefilling_size=7322870 prefilling_elapsed_millis=2.705s
prefilling_size=7448494 prefilling_elapsed_millis=2.805s
prefilling_size=7567615 prefilling_elapsed_millis=2.905s
prefilling_size=7681919 prefilling_elapsed_millis=3.006s
prefilling_size=7790067 prefilling_elapsed_millis=3.106s
prefilling_size=7893718 prefilling_elapsed_millis=3.206s
prefilling_size=7992378 prefilling_elapsed_millis=3.306s
prefilling_size=8086062 prefilling_elapsed_millis=3.407s
prefilling_size=8174806 prefilling_elapsed_millis=3.507s
prefilling_size=8259442 prefilling_elapsed_millis=3.607s
prefilling_size=8339510 prefilling_elapsed_millis=3.707s
prefilling_size=8415870 prefilling_elapsed_millis=3.808s
prefilling_size=8487659 prefilling_elapsed_millis=3.908s
prefilling_size=8559098 prefilling_elapsed_millis=4.008s
prefilling_size=8626607 prefilling_elapsed_millis=4.108s
prefilling_size=8691015 prefilling_elapsed_millis=4.209s
prefilling_size=8752644 prefilling_elapsed_millis=4.309s
prefilling_size=8810531 prefilling_elapsed_millis=4.409s
prefilling_size=8866462 prefilling_elapsed_millis=4.509s
prefilling_size=8919462 prefilling_elapsed_millis=4.61s
prefilling_size=8969924 prefilling_elapsed_millis=4.71s
prefilling_size=9017423 prefilling_elapsed_millis=4.81s
prefilling_size=9062876 prefilling_elapsed_millis=4.91s
prefilling_size=9107850 prefilling_elapsed_millis=5.01s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 892090 keys with 64 threads...
tid=0 thread_prefill_amount=137500 percent_done_estimate=986.4 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=140625 percent_done_estimate=1008.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=143750 percent_done_estimate=1031.3 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=146875 percent_done_estimate=1053.7 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=150000 percent_done_estimate=1076.1 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.107523s
prefilling_size=10000000 prefilling_elapsed_millis=0.112s
finished prefilling to size 10000000 for expected size 10000000 keysum=99998557351716, performing 50204630 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=211146.230872904
REALTIME_END_PERF_FORMAT=211156.230944731

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10010536
tree_stats_avgDegreeAtDepth=1.00105e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00105e+07
tree_stats_avgDegree=1.00105e+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=282050 282482 281714 281459 283257 283148 282046 281686 283408 281591 281102 281410 281630 282137 281093 281406 281762 280971 281251 281466 282252 282061 281321 282214 280952 280829 280776 280390 281039 281478 281837 281321 281576 281480 280590 282033 282015 282158 281201 281865 281244 281006 280659 280308 281597 280063 281328 280847 280093 279966 279709 278831 279624 280499 279469 279421 279439 280681 280160 279389 280636 279872 278884 279401
sum_num_inserts_total=17989583
sum_num_deletes_by_thread=282861 282300 282407 281958 282062 282415 282045 282479 282050 282632 281552 281063 281503 281914 281378 280613 282368 282228 280863 282011 281881 282536 281470 281191 281400 281405 280599 280518 282179 281885 279854 281317 281004 281459 281444 281607 282444 281060 282214 281671 281373 281024 280787 280326 281426 281279 280387 281033 280282 280609 279696 279722 280545 281027 279458 279180 280234 279572 279433 279545 280788 280638 279177 279471
sum_num_deletes_total=17994852
sum_num_searches_by_thread=5089423 5089629 5081217 5062302 5090967 5085264 5082583 5067840 5070968 5070219 5067724 5067306 5076436 5071686 5068721 5070116 5074993 5074341 5067851 5069596 5080134 5071171 5066403 5069521 5061808 5060675 5051779 5055628 5058660 5053829 5053547 5055323 5079744 5075969 5071140 5068255 5080021 5076837 5069269 5069511 5062716 5062418 5053638 5051221 5060518 5063042 5051842 5054130 5045061 5039831 5036128 5035776 5047998 5042794 5034104 5037075 5042453 5039025 5030380 5037139 5045028 5042151 5038038 5038473
sum_num_searches_total=323919385
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5654334 5654411 5645338 5625719 5656286 5650827 5646674 5632005 5636426 5634442 5630378 5629779 5639569 5635737 5631192 5632135 5639123 5637540 5629965 5633073 5644267 5635768 5629194 5632926 5624160 5622909 5613154 5616536 5621878 5617192 5615238 5617961 5642324 5638908 5633174 5631895 5644480 5640055 5632684 5633047 5625333 5624448 5615084 5611855 5623541 5624384 5613557 5616010 5605436 5600406 5595533 5594329 5608167 5604320 5593031 5595676 5602126 5599278 5589973 5596073 5606452 5602661 5596099 5597345
average_num_operations_total=5623497
stdev_num_operations_total=2199
sum_num_operations_total=359903820
min_num_operations_total=5589973
max_num_operations_total=5656286
first_time_thread_terminate_by_thread=10000116 10000071 10000071 10000071 10000071 10000072 10000072 10000071 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000072 10000071 10000072 10000071 10000075 10000075 10000075 10000075 10000074 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000073 10000073 10000075 10000073 10000073 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000073 10000073 10000073 10000073
min_time_thread_terminate_total=10000071
max_time_thread_terminate_total=10000116
first_time_thread_start_by_thread=8 2 2 2 7 7 7 7 5 5 5 5 4 5 4 5 6 6 6 6 6 6 6 6 7 7 7 7 5 5 5 5 4 4 4 4 4 4 4 4 3 3 3 3 7 7 7 7 4 4 4 4 7 7 7 7 6 6 6 6 6 6 6 6
min_time_thread_start_total=2
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=100104671006630
threads_final_size=10010536
final_keysum=100104671006630
final_size=10010536
validate_result=success
Validation OK.
Structural validation OK.

total_find=323919385
total_rq=0
total_inserts=17989583
total_deletes=17994852
total_updates=35984435
total_queries=323919385
total_ops=359903820
find_throughput=32391938
rq_throughput=0
update_throughput=3598443
query_throughput=32391938
total_throughput=35990382


total find                    : 323919385
total rq                      : 0
total inserts                 : 17989583
total deletes                 : 17994852
total updates                 : 35984435
total queries                 : 323919385
total ops                     : 359903820
find throughput               : 32391938
rq throughput                 : 0
update throughput             : 3598443
query throughput              : 32391938
total throughput              : 35990382

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=126238528262843
total_execution_walltime=15.646s
REALTIME_MAIN_END_PERF_FORMAT=211156.601216881
[time_cmd_output] time_elapsed_sec=15.81, faults_major=0, faults_minor=291839, mem_maxresident_kb=1134092, user_cputime=973.54, sys_cputime=4.93, percent_cpu=6187%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2