__step=000027
__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 128 -nprefill 128 -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=data000027.txt
__path_data=data/data000027.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207098.207888479
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=128
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=128
WORK_THREADS=128
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,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189,190,191


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.034s
prefilling_size=5559225 prefilling_elapsed_millis=1.001s
prefilling_size=5904604 prefilling_elapsed_millis=1.101s
prefilling_size=6219036 prefilling_elapsed_millis=1.201s
prefilling_size=6509263 prefilling_elapsed_millis=1.301s
prefilling_size=6774813 prefilling_elapsed_millis=1.401s
prefilling_size=7020076 prefilling_elapsed_millis=1.501s
prefilling_size=7244719 prefilling_elapsed_millis=1.601s
prefilling_size=7449726 prefilling_elapsed_millis=1.701s
prefilling_size=7642522 prefilling_elapsed_millis=1.802s
prefilling_size=7818649 prefilling_elapsed_millis=1.902s
prefilling_size=7980531 prefilling_elapsed_millis=2.002s
prefilling_size=8131529 prefilling_elapsed_millis=2.102s
prefilling_size=8268203 prefilling_elapsed_millis=2.202s
prefilling_size=8396107 prefilling_elapsed_millis=2.302s
prefilling_size=8512976 prefilling_elapsed_millis=2.402s
prefilling_size=8622601 prefilling_elapsed_millis=2.503s
prefilling_size=8721715 prefilling_elapsed_millis=2.603s
prefilling_size=8813253 prefilling_elapsed_millis=2.703s
prefilling_size=8898979 prefilling_elapsed_millis=2.803s
prefilling_size=8980619 prefilling_elapsed_millis=2.903s
prefilling_size=9054696 prefilling_elapsed_millis=3.004s
prefilling_size=9123921 prefilling_elapsed_millis=3.104s
prefilling_size=9187715 prefilling_elapsed_millis=3.204s
prefilling_size=9245748 prefilling_elapsed_millis=3.304s
prefilling_size=9299617 prefilling_elapsed_millis=3.404s
prefilling_size=9349646 prefilling_elapsed_millis=3.504s
prefilling_size=9394977 prefilling_elapsed_millis=3.604s
prefilling_size=9436223 prefilling_elapsed_millis=3.705s
prefilling_size=9475979 prefilling_elapsed_millis=3.805s
prefilling_size=9512948 prefilling_elapsed_millis=3.905s
prefilling_size=9548017 prefilling_elapsed_millis=4.005s
prefilling_size=9580675 prefilling_elapsed_millis=4.105s
prefilling_size=9609423 prefilling_elapsed_millis=4.205s
prefilling_size=9636376 prefilling_elapsed_millis=4.305s
prefilling_size=9662419 prefilling_elapsed_millis=4.405s
prefilling_size=9685921 prefilling_elapsed_millis=4.506s
prefilling_size=9706926 prefilling_elapsed_millis=4.606s
prefilling_size=9727937 prefilling_elapsed_millis=4.706s
prefilling_size=9745879 prefilling_elapsed_millis=4.806s
prefilling_size=9764768 prefilling_elapsed_millis=4.906s
prefilling_size=9779563 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 220429 keys with 128 threads...
tid=0 thread_prefill_amount=96844 percent_done_estimate=5623.6 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0260231s
prefilling_size=10000000 prefilling_elapsed_millis=0.027s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994865695403, performing 83967642 updates; total_prefilling_elapsed_ms=27 ms)
pref_size=10000000
pref_millis=27
prefill_elapsed_ms=27
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207103.426996611
REALTIME_END_PERF_FORMAT=207113.427079095

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10008129
tree_stats_avgDegreeAtDepth=1.00081e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00081e+07
tree_stats_avgDegree=1.00081e+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=32033 32016 32228 31810 31958 31840 32008 31525 31927 31641 31831 32140 31814 32085 31741 31826 31928 32076 32130 32253 32192 32080 31960 31802 31607 31703 32164 31787 31997 31690 31850 31799 32396 32573 32461 32333 32728 32308 32337 32680 32491 32581 32236 32571 32340 32617 32143 32215 31806 32288 32118 31839 32004 32246 32101 32164 32266 32256 32157 31721 32212 31798 31829 32163 32167 31827 32141 32288 32068 31720 31874 31798 31752 31810 31929 31716 31852 31869 31816 32054 32295 32381 31907 32123 32394 32115 32052 32054 32094 31752 32193 31872 32025 31762 31919 31854 32721 32664 32895 32467 32597 32507 32343 32284 32326 32414 32483 32507 32140 32618 32741 32356 32159 32079 31734 31850 31995 31930 31967 31839 32222 32249 31919 31993 32141 31999 31811 32021
sum_num_inserts_total=4108863
sum_num_deletes_by_thread=31830 32131 31941 31745 32120 31464 31935 31772 32133 32190 32124 32062 31789 32156 31640 31619 32053 32529 32103 31983 32117 32098 32209 32047 31966 31891 31974 31752 31950 32129 31807 31820 32519 32813 32387 32070 32669 32443 32375 32321 32265 32392 32277 32653 32177 32353 32450 32018 32025 32360 32124 32110 32297 32101 31885 31983 32476 32093 31912 32183 32070 31816 31932 31902 32295 32270 31966 32174 32116 32021 32184 31624 31753 31806 31625 31765 32299 31895 31609 31683 31956 32140 32169 32077 31937 32093 31858 31968 32005 32164 31782 32035 31917 32187 31674 31453 32551 32581 32535 32781 32454 32352 32328 32522 32744 32102 32432 32124 32341 32583 32493 32615 31962 32168 32081 31928 32152 32040 31910 31868 31876 32307 31775 31968 31922 31905 32280 31515
sum_num_deletes_total=4108225
sum_num_searches_by_thread=6332505 6337848 6329198 6290933 6340041 6327784 6325085 6321549 6309248 6323496 6309547 6304074 6322523 6311343 6298529 6300474 6360777 6363250 6345557 6353064 6360588 6355609 6342872 6341294 6328743 6337149 6323769 6310986 6326758 6329336 6314111 6316188 6461408 6456324 6443821 6451219 6459644 6455875 6444511 6433992 6415267 6430887 6412177 6404012 6414423 6415629 6386914 6401425 6353076 6354910 6345401 6345931 6353933 6354855 6339946 6326953 6352059 6357646 6339021 6339943 6348100 6356279 6337213 6341882 6333443 6337970 6331062 6317180 6337811 6334418 6323141 6319135 6310317 6317774 6306260 6302321 6319994 6310888 6302828 6299877 6356622 6362726 6346177 6348289 6353858 6356528 6345828 6338463 6327965 6326923 6323636 6311205 6329348 6327240 6311576 6314949 6458947 6457433 6442969 6446574 6451675 6452868 6449820 6432993 6427169 6420501 6414481 6406229 6412786 6420694 6405113 6401102 6356896 6357776 6342124 6348603 6354160 6356290 6343668 6330643 6348641 6365867 6341194 6340993 6358842 6352934 6336546 6341272
sum_num_searches_total=813892459
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6396368 6401995 6393367 6354488 6404119 6391088 6389028 6384846 6373308 6387327 6373502 6368276 6386126 6375584 6361910 6363919 6424758 6427855 6409790 6417300 6424897 6419787 6407041 6405143 6392316 6400743 6387907 6374525 6390705 6393155 6377768 6379807 6526323 6521710 6508669 6515622 6525041 6520626 6509223 6498993 6480023 6495860 6476690 6469236 6478940 6480599 6451507 6465658 6416907 6419558 6409643 6409880 6418234 6419202 6403932 6391100 6416801 6421995 6403090 6403847 6412382 6419893 6400974 6405947 6397905 6402067 6395169 6381642 6401995 6398159 6387199 6382557 6373822 6381390 6369814 6365802 6384145 6374652 6366253 6363614 6420873 6427247 6410253 6412489 6418189 6420736 6409738 6402485 6392064 6390839 6387611 6375112 6393290 6391189 6375169 6378256 6524219 6522678 6508399 6511822 6516726 6517727 6514491 6497799 6492239 6485017 6479396 6470860 6477267 6485895 6470347 6466073 6421017 6422023 6405939 6412381 6418307 6420260 6407545 6394350 6412739 6430423 6404888 6404954 6422905 6416838 6400637 6404808
average_num_operations_total=6422730
stdev_num_operations_total=4125
sum_num_operations_total=822109547
min_num_operations_total=6354488
max_num_operations_total=6526323
first_time_thread_terminate_by_thread=10000136 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000087 10000087 10000087 10000087 10000082 10000082 10000082 10000082 10000085 10000085 10000085 10000085 10000084 10000084 10000084 10000084 10000087 10000087 10000087 10000087 10000084 10000084 10000084 10000084 10000084 10000084 10000085 10000084 10000086 10000086 10000086 10000086 10000085 10000085 10000085 10000085 10000087 10000087 10000087 10000087 10000086 10000086 10000086 10000086 10000086 10000087 10000086 10000086 10000084 10000084 10000084 10000084 10000086 10000086 10000086 10000086 10000082 10000082 10000082 10000082 10000082 10000082 10000085 10000082 10000087 10000087 10000087 10000087 10000082 10000084 10000082 10000082 10000085 10000085 10000085 10000085 10000084 10000084 10000084 10000084 10000087 10000087 10000087 10000087 10000084 10000084 10000084 10000084 10000084 10000084 10000085 10000084 10000086 10000086 10000086 10000086 10000085 10000085 10000085 10000085 10000087 10000087 10000087 10000087 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000084 10000084 10000084 10000084 10000086 10000086 10000086 10000086
min_time_thread_terminate_total=10000082
max_time_thread_terminate_total=10000136
first_time_thread_start_by_thread=11 5 5 5 6 6 6 6 8 8 7 8 5 5 5 5 7 7 7 7 6 6 6 6 8 8 8 8 7 7 7 7 9 9 9 9 6 6 6 6 9 9 9 9 7 7 7 7 10 10 10 10 8 8 8 8 10 10 10 10 9 9 9 9 5 5 5 5 6 6 6 6 8 7 7 8 5 5 5 5 7 7 7 7 6 6 6 6 8 8 8 8 7 7 7 7 9 9 9 9 6 6 6 6 9 9 9 9 7 7 7 7 10 10 10 10 8 8 8 8 10 10 10 10 9 9 9 9
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=100085499491800
threads_final_size=10008129
final_keysum=100085499491800
final_size=10008129
validate_result=success
Validation OK.
Structural validation OK.

total_find=813892459
total_rq=0
total_inserts=4108863
total_deletes=4108225
total_updates=8217088
total_queries=813892459
total_ops=822109547
find_throughput=81389245
rq_throughput=0
update_throughput=821708
query_throughput=81389245
total_throughput=82210954


total find                    : 813892459
total rq                      : 0
total inserts                 : 4108863
total deletes                 : 4108225
total updates                 : 8217088
total queries                 : 813892459
total ops                     : 822109547
find throughput               : 81389245
rq throughput                 : 0
update throughput             : 821708
query throughput              : 81389245
total throughput              : 82210954

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=541307989961751
total_execution_walltime=15.583s
REALTIME_MAIN_END_PERF_FORMAT=207113.791861950
[time_cmd_output] time_elapsed_sec=15.78, faults_major=0, faults_minor=372055, mem_maxresident_kb=1474496, user_cputime=1932.24, sys_cputime=6.50, percent_cpu=12284%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1