__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000362.txt
__path_data=data/data000362.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983133.298802180
binary=./wang_openbwtree
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=5091568 prefilling_elapsed_millis=1.001s
prefilling_size=5420694 prefilling_elapsed_millis=1.101s
prefilling_size=5725642 prefilling_elapsed_millis=1.201s
prefilling_size=6010418 prefilling_elapsed_millis=1.301s
prefilling_size=6274526 prefilling_elapsed_millis=1.402s
prefilling_size=6520142 prefilling_elapsed_millis=1.502s
prefilling_size=6749405 prefilling_elapsed_millis=1.602s
prefilling_size=6962717 prefilling_elapsed_millis=1.702s
prefilling_size=7160847 prefilling_elapsed_millis=1.802s
prefilling_size=7345831 prefilling_elapsed_millis=1.903s
prefilling_size=7518830 prefilling_elapsed_millis=2.003s
prefilling_size=7678059 prefilling_elapsed_millis=2.103s
prefilling_size=7828650 prefilling_elapsed_millis=2.203s
prefilling_size=7968197 prefilling_elapsed_millis=2.303s
prefilling_size=8097963 prefilling_elapsed_millis=2.403s
prefilling_size=8220682 prefilling_elapsed_millis=2.504s
prefilling_size=8334606 prefilling_elapsed_millis=2.604s
prefilling_size=8439299 prefilling_elapsed_millis=2.704s
prefilling_size=8537976 prefilling_elapsed_millis=2.804s
prefilling_size=8629815 prefilling_elapsed_millis=2.904s
prefilling_size=8716341 prefilling_elapsed_millis=3.005s
prefilling_size=8798349 prefilling_elapsed_millis=3.105s
prefilling_size=8874709 prefilling_elapsed_millis=3.205s
prefilling_size=8945871 prefilling_elapsed_millis=3.305s
prefilling_size=9012761 prefilling_elapsed_millis=3.405s
prefilling_size=9074256 prefilling_elapsed_millis=3.506s
prefilling_size=9132339 prefilling_elapsed_millis=3.606s
prefilling_size=9185133 prefilling_elapsed_millis=3.706s
prefilling_size=9235420 prefilling_elapsed_millis=3.806s
prefilling_size=9284068 prefilling_elapsed_millis=3.906s
prefilling_size=9329531 prefilling_elapsed_millis=4.007s
prefilling_size=9369980 prefilling_elapsed_millis=4.107s
prefilling_size=9409877 prefilling_elapsed_millis=4.207s
prefilling_size=9446765 prefilling_elapsed_millis=4.307s
prefilling_size=9480378 prefilling_elapsed_millis=4.407s
prefilling_size=9512120 prefilling_elapsed_millis=4.507s
prefilling_size=9542622 prefilling_elapsed_millis=4.608s
prefilling_size=9570757 prefilling_elapsed_millis=4.708s
prefilling_size=9597308 prefilling_elapsed_millis=4.808s
prefilling_size=9621203 prefilling_elapsed_millis=4.908s
prefilling_size=9644813 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 355115 keys with 48 threads...
tid=0 thread_prefill_amount=179138 percent_done_estimate=2421.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0445027s
prefilling_size=10000000 prefilling_elapsed_millis=0.044s
finished prefilling to size 10000000 for expected size 10000000 keysum=100014309574192, performing 70802635 updates; total_prefilling_elapsed_ms=44 ms)
pref_size=10000000
pref_millis=44
prefill_elapsed_ms=44
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983138.628153451
REALTIME_END_PERF_FORMAT=6983148.628233165

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10001928
tree_stats_avgDegreeAtDepth=1.00019e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00019e+07
tree_stats_avgDegree=1.00019e+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=335853 336483 338767 339396 338706 334273 334795 337309 341032 340002 337710 335912 338038 338480 339385 340535 337864 335519 335229 336313 338764 339476 336697 333513 336184 337727 337991 338955 337956 334534 336159 336243 339605 339160 338279 335554 337223 337544 339940 339505 337495 336643 334504 335246 338073 338069 337488 335021
sum_num_inserts_total=16195149
sum_num_deletes_by_thread=335010 337633 338652 339592 338652 335255 335616 336849 339564 340114 337359 335794 337219 338162 340463 340507 338507 336872 334549 335925 338046 338676 336108 335028 335683 336058 339443 339476 338497 334660 334809 337800 339792 339800 338059 334899 336696 338078 338510 340457 338359 336328 334290 335316 338887 338439 336316 334490
sum_num_deletes_total=16195294
sum_num_searches_by_thread=6038224 6062316 6102525 6106629 6097300 6037114 6052277 6065770 6117755 6117198 6078343 6041557 6063081 6086187 6113504 6122208 6093050 6054721 6029389 6043294 6086536 6107648 6050480 6023376 6054906 6070178 6103627 6105127 6093741 6031739 6050230 6068668 6117065 6110867 6076887 6044260 6059230 6082716 6109499 6124906 6093094 6052113 6025964 6045084 6084759 6106577 6047343 6018621
sum_num_searches_total=291567683
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6709087 6736432 6779944 6785617 6774658 6706642 6722688 6739928 6798351 6797314 6753412 6713263 6738338 6762829 6793352 6803250 6769421 6727112 6699167 6715532 6763346 6785800 6723285 6691917 6726773 6743963 6781061 6783558 6770194 6700933 6721198 6742711 6796462 6789827 6753225 6714713 6733149 6758338 6787949 6804868 6768948 6725084 6694758 6715646 6761719 6783085 6721147 6688132
average_num_operations_total=6749127
stdev_num_operations_total=5024
sum_num_operations_total=323958126
min_num_operations_total=6688132
max_num_operations_total=6804868
first_time_thread_terminate_by_thread=10000130 10000079 10000079 10000079 10000080 10000079 10000080 10000079 10000080 10000079 10000080 10000079 10000080 10000080 10000080 10000079 10000080 10000080 10000079 10000080 10000079 10000080 10000080 10000079 10000079 10000080 10000079 10000079 10000080 10000080 10000080 10002917 10000079 10000079 10000080 10000079 10000079 10000080 10000080 10000079 10000079 10000080 10000079 10000080 10000079 10000079 10000080 10000079
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10002917
first_time_thread_start_by_thread=9 6 6 6 5 6 5 6 6 6 5 5 6 6 6 6 6 6 5 6 6 6 6 6 5 6 6 6 5 6 5 6 6 6 5 5 6 6 6 6 6 6 5 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100021951031654
threads_final_size=10001928
final_keysum=100021951031654
final_size=10001928
validate_result=success
Validation OK.
Structural validation OK.

total_find=291567683
total_rq=0
total_inserts=16195149
total_deletes=16195294
total_updates=32390443
total_queries=291567683
total_ops=323958126
find_throughput=29156768
rq_throughput=0
update_throughput=3239044
query_throughput=29156768
total_throughput=32395812


total find                    : 291567683
total rq                      : 0
total inserts                 : 16195149
total deletes                 : 16195294
total updates                 : 32390443
total queries                 : 291567683
total ops                     : 323958126
find throughput               : 29156768
rq throughput                 : 0
update throughput             : 3239044
query throughput              : 29156768
total throughput              : 32395812

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.091
PAPI_L3_TCM=15.7257
PAPI_TOT_CYC=4102.55
PAPI_TOT_INS=835.052
garbage=639157958655461
total_execution_walltime=15.646s
REALTIME_MAIN_END_PERF_FORMAT=6983148.946021445
[time_cmd_output] time_elapsed_sec=15.82, faults_major=0, faults_minor=277913, mem_maxresident_kb=1093576, user_cputime=733.36, sys_cputime=4.08, percent_cpu=4658%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2