__step=000361
__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=data000361.txt
__path_data=data/data000361.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211125.71018533
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.021s
prefilling_size=3879414 prefilling_elapsed_millis=1s
prefilling_size=4167818 prefilling_elapsed_millis=1.1s
prefilling_size=4437406 prefilling_elapsed_millis=1.201s
prefilling_size=4696796 prefilling_elapsed_millis=1.301s
prefilling_size=4941493 prefilling_elapsed_millis=1.401s
prefilling_size=5175957 prefilling_elapsed_millis=1.501s
prefilling_size=5396316 prefilling_elapsed_millis=1.601s
prefilling_size=5605509 prefilling_elapsed_millis=1.701s
prefilling_size=5805203 prefilling_elapsed_millis=1.801s
prefilling_size=5994877 prefilling_elapsed_millis=1.901s
prefilling_size=6174716 prefilling_elapsed_millis=2.002s
prefilling_size=6347113 prefilling_elapsed_millis=2.102s
prefilling_size=6510479 prefilling_elapsed_millis=2.202s
prefilling_size=6667968 prefilling_elapsed_millis=2.302s
prefilling_size=6816575 prefilling_elapsed_millis=2.402s
prefilling_size=6959335 prefilling_elapsed_millis=2.502s
prefilling_size=7095281 prefilling_elapsed_millis=2.602s
prefilling_size=7223768 prefilling_elapsed_millis=2.702s
prefilling_size=7347888 prefilling_elapsed_millis=2.802s
prefilling_size=7472423 prefilling_elapsed_millis=2.903s
prefilling_size=7584886 prefilling_elapsed_millis=3.003s
prefilling_size=7691050 prefilling_elapsed_millis=3.103s
prefilling_size=7794147 prefilling_elapsed_millis=3.203s
prefilling_size=7890696 prefilling_elapsed_millis=3.304s
prefilling_size=7981820 prefilling_elapsed_millis=3.404s
prefilling_size=8068943 prefilling_elapsed_millis=3.504s
prefilling_size=8152026 prefilling_elapsed_millis=3.604s
prefilling_size=8231582 prefilling_elapsed_millis=3.704s
prefilling_size=8308302 prefilling_elapsed_millis=3.804s
prefilling_size=8380474 prefilling_elapsed_millis=3.904s
prefilling_size=8450246 prefilling_elapsed_millis=4.004s
prefilling_size=8517019 prefilling_elapsed_millis=4.104s
prefilling_size=8579877 prefilling_elapsed_millis=4.205s
prefilling_size=8640249 prefilling_elapsed_millis=4.305s
prefilling_size=8698500 prefilling_elapsed_millis=4.405s
prefilling_size=8754006 prefilling_elapsed_millis=4.505s
prefilling_size=8807288 prefilling_elapsed_millis=4.605s
prefilling_size=8856257 prefilling_elapsed_millis=4.705s
prefilling_size=8903913 prefilling_elapsed_millis=4.805s
prefilling_size=8949876 prefilling_elapsed_millis=4.905s
prefilling_size=8994711 prefilling_elapsed_millis=5.005s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1005261 keys with 64 threads...
tid=0 thread_prefill_amount=121875 percent_done_estimate=775.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=125000 percent_done_estimate=795.8 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=128125 percent_done_estimate=815.7 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=131250 percent_done_estimate=835.6 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=134375 percent_done_estimate=855.5 elapsed_s=5 est_remaining_s=-10 / -5
timing_elapsed 0.118526s
prefilling_size=10000000 prefilling_elapsed_millis=0.124s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994114828584, performing 50611021 updates; total_prefilling_elapsed_ms=124 ms)
pref_size=10000000
pref_millis=124
prefill_elapsed_ms=124
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211130.350874704
REALTIME_END_PERF_FORMAT=211140.350946732

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9964251
tree_stats_avgDegreeAtDepth=9.96425e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.96425e+06
tree_stats_avgDegree=9.96425e+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=283278 282637 283009 282137 282314 282040 282385 282411 283040 281842 280912 281620 282960 281136 281343 281481 282043 282164 281763 282142 281622 281635 281396 281332 281043 281329 281236 280384 281106 280732 281248 280081 282068 282732 281083 282234 282286 283112 282061 282251 281358 280526 282960 281350 281791 281051 280363 281690 281263 280932 280190 280875 280771 280476 279824 279843 280579 280891 280026 279763 279748 280754 279427 279934
sum_num_inserts_total=18010013
sum_num_deletes_by_thread=282602 282716 282362 281308 283183 282747 281910 282917 282442 282090 282819 281992 280545 281721 282386 281750 282672 281560 281218 280968 281581 282238 281951 282007 282104 280378 280671 281065 281448 282109 280641 280639 283234 282905 282613 282190 282879 282493 282794 282385 282374 282419 281990 281353 281563 281229 281597 283076 280836 280602 280743 279963 280881 280883 279744 278624 280772 280277 281051 279809 280722 280645 280101 280141
sum_num_deletes_total=18021628
sum_num_searches_by_thread=5091810 5093367 5082090 5066185 5100260 5095091 5074367 5086969 5082881 5082886 5068599 5074438 5081755 5077068 5068343 5070847 5079932 5072184 5066552 5076612 5080219 5078209 5069686 5067153 5065523 5060344 5053201 5058172 5065011 5058609 5055456 5060550 5089956 5088785 5078336 5084954 5094633 5082168 5079823 5083365 5075935 5065593 5059196 5064878 5075856 5072774 5060884 5060571 5050914 5041504 5038114 5041091 5050736 5048118 5042554 5039951 5052356 5048316 5037547 5044686 5052132 5048390 5041197 5042854
sum_num_searches_total=324302536
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5657690 5658720 5647461 5629630 5665757 5659878 5638662 5652297 5648363 5646818 5632330 5638050 5645260 5639925 5632072 5634078 5644647 5635908 5629533 5639722 5643422 5642082 5633033 5630492 5628670 5622051 5615108 5619621 5627565 5621450 5617345 5621270 5655258 5654422 5642032 5649378 5659798 5647773 5644678 5648001 5639667 5628538 5624146 5627581 5639210 5635054 5622844 5625337 5613013 5603038 5599047 5601929 5612388 5609477 5602122 5598418 5613707 5609484 5598624 5604258 5612602 5609789 5600725 5602929
average_num_operations_total=5630221
stdev_num_operations_total=2288
sum_num_operations_total=360334177
min_num_operations_total=5598418
max_num_operations_total=5665757
first_time_thread_terminate_by_thread=10000115 10000075 10000075 10000075 10000075 10000077 10000075 10000075 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000072 10000076 10000072 10000072 10000076 10000076 10000076 10000076 10000074 10000074 10000074 10000074 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000074 10000074 10000074 10000074 10000073 10000076 10000073 10000073 10000074 10000074 10000075 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000075 10000075 10000075 10000075
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000115
first_time_thread_start_by_thread=11 4 4 4 5 6 6 6 5 5 5 5 10 10 10 10 12 12 12 11 6 6 6 6 8 8 8 8 11 11 11 11 7 7 7 7 5 5 5 5 9 9 9 9 7 7 7 7 10 10 10 10 7 7 7 7 6 6 6 6 10 9 9 9
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=99647894766216
threads_final_size=9964251
final_keysum=99647894766216
final_size=9964251
validate_result=success
Validation OK.
Structural validation OK.

total_find=324302536
total_rq=0
total_inserts=18010013
total_deletes=18021628
total_updates=36031641
total_queries=324302536
total_ops=360334177
find_throughput=32430253
rq_throughput=0
update_throughput=3603164
query_throughput=32430253
total_throughput=36033417


total find                    : 324302536
total rq                      : 0
total inserts                 : 18010013
total deletes                 : 18021628
total updates                 : 36031641
total queries                 : 324302536
total ops                     : 360334177
find throughput               : 32430253
rq throughput                 : 0
update throughput             : 3603164
query throughput              : 32430253
total throughput              : 36033417

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=203141961211597
total_execution_walltime=15.648s
REALTIME_MAIN_END_PERF_FORMAT=211140.719823994
[time_cmd_output] time_elapsed_sec=15.81, faults_major=0, faults_minor=291912, mem_maxresident_kb=1134288, user_cputime=973.75, sys_cputime=5.02, percent_cpu=6189%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1