__step=000026
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000026.txt
__path_data=data/data000026.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979009.366481137
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=0.5
DEL_FRAC=0.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=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,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.006s
prefilling_size=5224201 prefilling_elapsed_millis=1.001s
prefilling_size=5558483 prefilling_elapsed_millis=1.101s
prefilling_size=5867911 prefilling_elapsed_millis=1.201s
prefilling_size=6156754 prefilling_elapsed_millis=1.301s
prefilling_size=6421808 prefilling_elapsed_millis=1.402s
prefilling_size=6668754 prefilling_elapsed_millis=1.502s
prefilling_size=6898731 prefilling_elapsed_millis=1.602s
prefilling_size=7113218 prefilling_elapsed_millis=1.702s
prefilling_size=7312104 prefilling_elapsed_millis=1.802s
prefilling_size=7498037 prefilling_elapsed_millis=1.903s
prefilling_size=7671348 prefilling_elapsed_millis=2.003s
prefilling_size=7831510 prefilling_elapsed_millis=2.103s
prefilling_size=7980823 prefilling_elapsed_millis=2.203s
prefilling_size=8121861 prefilling_elapsed_millis=2.303s
prefilling_size=8250402 prefilling_elapsed_millis=2.404s
prefilling_size=8370484 prefilling_elapsed_millis=2.504s
prefilling_size=8482892 prefilling_elapsed_millis=2.604s
prefilling_size=8587811 prefilling_elapsed_millis=2.704s
prefilling_size=8684323 prefilling_elapsed_millis=2.804s
prefilling_size=8776462 prefilling_elapsed_millis=2.905s
prefilling_size=8859729 prefilling_elapsed_millis=3.005s
prefilling_size=8938370 prefilling_elapsed_millis=3.105s
prefilling_size=9011109 prefilling_elapsed_millis=3.205s
prefilling_size=9079464 prefilling_elapsed_millis=3.305s
prefilling_size=9142592 prefilling_elapsed_millis=3.406s
prefilling_size=9202054 prefilling_elapsed_millis=3.506s
prefilling_size=9258760 prefilling_elapsed_millis=3.606s
prefilling_size=9309881 prefilling_elapsed_millis=3.706s
prefilling_size=9358304 prefilling_elapsed_millis=3.806s
prefilling_size=9401294 prefilling_elapsed_millis=3.906s
prefilling_size=9441530 prefilling_elapsed_millis=4.007s
prefilling_size=9480882 prefilling_elapsed_millis=4.107s
prefilling_size=9516393 prefilling_elapsed_millis=4.207s
prefilling_size=9550682 prefilling_elapsed_millis=4.307s
prefilling_size=9582277 prefilling_elapsed_millis=4.407s
prefilling_size=9611708 prefilling_elapsed_millis=4.508s
prefilling_size=9637565 prefilling_elapsed_millis=4.608s
prefilling_size=9662055 prefilling_elapsed_millis=4.708s
prefilling_size=9685659 prefilling_elapsed_millis=4.808s
prefilling_size=9707559 prefilling_elapsed_millis=4.908s
prefilling_size=9727771 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 272222 keys with 48 threads...
tid=0 thread_prefill_amount=204134 percent_done_estimate=3599.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0352168s
prefilling_size=10000000 prefilling_elapsed_millis=0.035s
finished prefilling to size 10000000 for expected size 10000000 keysum=99990781417395, performing 72501400 updates; total_prefilling_elapsed_ms=35 ms)
pref_size=10000000
pref_millis=35
prefill_elapsed_ms=35
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979014.692154752
REALTIME_END_PERF_FORMAT=6979024.692234550

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9999731
tree_stats_avgDegreeAtDepth=9.99973e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99973e+06
tree_stats_avgDegree=9.99973e+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=37739 38184 38156 38557 37894 37886 37943 38301 38313 38060 38023 37840 38331 38002 38636 38155 38104 38081 37771 37524 37677 38249 37733 37533 37893 38493 38277 38308 37897 37877 38350 37852 38360 38492 38002 37633 38063 37990 38471 38729 38453 37867 37950 37737 38451 38561 38017 37814
sum_num_inserts_total=1828229
sum_num_deletes_by_thread=37981 38385 38075 37947 38005 37684 38178 38051 38499 38514 38065 37925 37860 38287 38344 38690 38014 37953 37400 38044 38381 38297 37734 37474 37766 38016 38523 38283 37991 37626 37601 38146 38439 38371 38207 38110 37926 38449 38375 38507 38641 38149 37831 37883 38206 38512 37862 37447
sum_num_deletes_total=1828654
sum_num_searches_by_thread=7490084 7538964 7593223 7581393 7563563 7488672 7506864 7532815 7602661 7608088 7535945 7496270 7534500 7574380 7611092 7604874 7558579 7509359 7482565 7514184 7546385 7575953 7500504 7444705 7506493 7536981 7593150 7578141 7557610 7484759 7503012 7528438 7596515 7598921 7530929 7485858 7523161 7569526 7609958 7610102 7555475 7515474 7482694 7514445 7549300 7577895 7500626 7442770
sum_num_searches_total=361947855
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7565804 7615533 7669454 7657897 7639462 7564242 7582985 7609167 7679473 7684662 7612033 7572035 7610691 7650669 7688072 7681719 7634697 7585393 7557736 7589752 7622443 7652499 7575971 7519712 7582152 7613490 7669950 7654732 7633498 7560262 7578963 7604436 7673314 7675784 7607138 7561601 7599150 7645965 7686804 7687338 7632569 7591490 7558475 7590065 7625957 7654968 7576505 7518031
average_num_operations_total=7616765
stdev_num_operations_total=6722
sum_num_operations_total=365604738
min_num_operations_total=7518031
max_num_operations_total=7688072
first_time_thread_terminate_by_thread=10000137 10000080 10000080 10000080 10000080 10000080 10000080 10000079 10000080 10000080 10000080 10000081 10000079 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000079 10000079 10000080 10000080 10000080 10000080 10000080 10000079 10000080 10000080 10000080 10000080 10000079 10000079 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=8 5 5 4 4 4 5 5 5 5 5 5 4 5 5 5 5 4 5 5 4 5 5 5 4 5 5 4 4 4 5 5 5 5 5 5 4 5 5 5 5 4 5 5 4 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99995917911695
threads_final_size=9999731
final_keysum=99995917911695
final_size=9999731
validate_result=success
Validation OK.
Structural validation OK.

total_find=361947855
total_rq=0
total_inserts=1828229
total_deletes=1828654
total_updates=3656883
total_queries=361947855
total_ops=365604738
find_throughput=36194785
rq_throughput=0
update_throughput=365688
query_throughput=36194785
total_throughput=36560473


total find                    : 361947855
total rq                      : 0
total inserts                 : 1828229
total deletes                 : 1828654
total updates                 : 3656883
total queries                 : 361947855
total ops                     : 365604738
find throughput               : 36194785
rq throughput                 : 0
update throughput             : 365688
query throughput              : 36194785
total throughput              : 36560473

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.6392
PAPI_L3_TCM=14.5915
PAPI_TOT_CYC=3643.43
PAPI_TOT_INS=782.018
garbage=1771950366177880
total_execution_walltime=15.637s
REALTIME_MAIN_END_PERF_FORMAT=6979025.4495939
[time_cmd_output] time_elapsed_sec=15.83, faults_major=0, faults_minor=266226, mem_maxresident_kb=1062364, user_cputime=733.34, sys_cputime=3.92, percent_cpu=4657%
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