__step=000138
__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 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000138.txt
__path_data=data/data000138.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=208528.469764902
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=2000000
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.016s
prefilling_size=998083 prefilling_elapsed_millis=1s
finished prefilling to size 998095 for expected size 1000000 keysum=998196535134, performing 13184029 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=998095
pref_millis=1101
prefill_elapsed_ms=1114
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=208529.618970702
REALTIME_END_PERF_FORMAT=208539.619051006
###############################################################################
################################## TIME IS UP #################################
###############################################################################
joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################
10s
PRODUCING OUTPUT
Open BwTree: createTreeStats iterating over all kv-pairs...
Open BwTree: createTreeStats finished iterating.
tree_stats_computeWalltime=0.025s
tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=1000385
tree_stats_avgDegreeAtDepth=1.00038e+06
tree_stats_height=1
tree_stats_numInternals=0
tree_stats_numLeaves=1
tree_stats_numNodes=1
tree_stats_numKeys=1000385
tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00038e+06
tree_stats_avgDegree=1.00039e+06
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=92984 92406 92868 92457 92780 92868 92946 92499 92586 92769 93021 93005 92654 93106 93253 92497 92443 91993 91589 91790 92147 92671 92007 91793 91567 91966 91635 91539 92079 92109 92015 92043 92044 92074 91696 91522 91934 92050 92095 91856 92082 92098 92139 92090 92015 91601 92035 91736 91382 91984 91299 91206 91008 92282 91008 91570 90949 91106 91458 90862 91213 91224 91370 92113
sum_num_inserts_total=5889186
sum_num_deletes_by_thread=92216 92231 92799 91609 92665 93341 92984 92656 92819 92839 92550 92633 92731 92367 92902 92408 92379 91765 92464 92109 92172 91800 92155 92397 92179 92220 91784 91563 91735 92551 91993 91970 92162 91903 91259 92305 92141 92502 92072 92294 92150 91772 91847 92301 92251 91862 92436 91822 90752 91680 91410 91438 91736 91132 90786 91358 91007 91428 91598 91529 91237 91914 91424 90894
sum_num_deletes_total=5889388
sum_num_searches_by_thread=18378394 18310532 18357260 18297396 18391425 18367560 18383903 18372643 18349640 18395258 18369735 18382510 18354341 18382646 18374416 18341098 18213746 18226716 18228499 18202313 18201266 18244111 18221013 18231037 18219699 18245672 18229186 18156125 18208332 18239163 18205672 18225250 18207695 18211092 18205360 18209915 18201436 18213879 18209679 18188520 18212396 18191203 18174380 18170946 18198522 18200481 18200672 18172597 18091717 18104616 18079052 18080036 18075900 18101555 18051635 18085450 18077899 18096615 18071227 18058394 18080582 18086980 18111546 18104706
sum_num_searches_total=1165833240
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=18563594 18495169 18542927 18481462 18576870 18553769 18569833 18557798 18535045 18580866 18555306 18568148 18539726 18568119 18560571 18526003 18398568 18410474 18412552 18386212 18385585 18428582 18405175 18415227 18403445 18429858 18412605 18339227 18392146 18423823 18389680 18409263 18391901 18395069 18388315 18393742 18385511 18398431 18393846 18372670 18396628 18375073 18358366 18355337 18382788 18383944 18385143 18356155 18273851 18288280 18261761 18262680 18258644 18284969 18233429 18268378 18259855 18279149 18254283 18240785 18263032 18270118 18294340 18287713
average_num_operations_total=18400184
stdev_num_operations_total=12942
sum_num_operations_total=1177611814
min_num_operations_total=18233429
max_num_operations_total=18580866
first_time_thread_terminate_by_thread=10000085 10000085 10000085 10000085 10000085 10000085 10000085 10000085 10000083 10000083 10000083 10000083 10000081 10000081 10000081 10000081 10000083 10000083 10000083 10000083 10000084 10000084 10000084 10000084 10000084 10000084 10000084 10000084 10000082 10000082 10000082 10000082 10000085 10000085 10000085 10000085 10000082 10000082 10000082 10000082 10000083 10000083 10000083 10000083 10000081 10000081 10000081 10000081 10000084 10000084 10000084 10000084 10000082 10000082 10000082 10000082 10000083 10000083 10000083 10000083 10000082 10000082 10000082 10000082
min_time_thread_terminate_total=10000081
max_time_thread_terminate_total=10000085
first_time_thread_start_by_thread=9 9 9 9 10 10 10 10 10 11 10 10 10 10 10 10 13 13 13 13 13 13 13 13 13 14 14 14 14 14 14 14 11 11 11 11 13 13 13 13 11 11 11 11 9 9 9 9 13 13 13 13 11 12 12 12 11 11 11 11 12 12 12 12
min_time_thread_start_total=9
max_time_thread_start_total=14
sum_duration_all_ops_total=
threads_final_keysum=1000843462124
threads_final_size=1000385
final_keysum=1000843462124
final_size=1000385
validate_result=success
Validation OK.
Structural validation OK.
total_find=1165833240
total_rq=0
total_inserts=5889186
total_deletes=5889388
total_updates=11778574
total_queries=1165833240
total_ops=1177611814
find_throughput=116583324
rq_throughput=0
update_throughput=1177857
query_throughput=116583324
total_throughput=117761181
total find : 1165833240
total rq : 0
total inserts : 5889186
total deletes : 5889388
total updates : 11778574
total queries : 1165833240
total ops : 1177611814
find throughput : 116583324
rq throughput : 0
update throughput : 1177857
query throughput : 116583324
total throughput : 117761181
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=146084458641275
total_execution_walltime=11.189s
REALTIME_MAIN_END_PERF_FORMAT=208539.660167179
[time_cmd_output] time_elapsed_sec=11.30, faults_major=0, faults_minor=125771, mem_maxresident_kb=438792, user_cputime=714.35, sys_cputime=4.54, percent_cpu=6359%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2