__step=000025
__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=data000025.txt
__path_data=data/data000025.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6978993.478569267
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.005s
prefilling_size=5147376 prefilling_elapsed_millis=1.001s
prefilling_size=5480067 prefilling_elapsed_millis=1.101s
prefilling_size=5789316 prefilling_elapsed_millis=1.201s
prefilling_size=6076523 prefilling_elapsed_millis=1.301s
prefilling_size=6345146 prefilling_elapsed_millis=1.402s
prefilling_size=6595159 prefilling_elapsed_millis=1.502s
prefilling_size=6827034 prefilling_elapsed_millis=1.602s
prefilling_size=7042679 prefilling_elapsed_millis=1.702s
prefilling_size=7243469 prefilling_elapsed_millis=1.802s
prefilling_size=7427631 prefilling_elapsed_millis=1.903s
prefilling_size=7601147 prefilling_elapsed_millis=2.003s
prefilling_size=7765166 prefilling_elapsed_millis=2.103s
prefilling_size=7915291 prefilling_elapsed_millis=2.203s
prefilling_size=8056086 prefilling_elapsed_millis=2.303s
prefilling_size=8185065 prefilling_elapsed_millis=2.404s
prefilling_size=8306468 prefilling_elapsed_millis=2.504s
prefilling_size=8419567 prefilling_elapsed_millis=2.604s
prefilling_size=8526302 prefilling_elapsed_millis=2.704s
prefilling_size=8624725 prefilling_elapsed_millis=2.804s
prefilling_size=8717485 prefilling_elapsed_millis=2.905s
prefilling_size=8802442 prefilling_elapsed_millis=3.005s
prefilling_size=8881510 prefilling_elapsed_millis=3.105s
prefilling_size=8956375 prefilling_elapsed_millis=3.205s
prefilling_size=9024567 prefilling_elapsed_millis=3.305s
prefilling_size=9089650 prefilling_elapsed_millis=3.406s
prefilling_size=9150967 prefilling_elapsed_millis=3.506s
prefilling_size=9207945 prefilling_elapsed_millis=3.606s
prefilling_size=9261823 prefilling_elapsed_millis=3.706s
prefilling_size=9312458 prefilling_elapsed_millis=3.806s
prefilling_size=9356638 prefilling_elapsed_millis=3.907s
prefilling_size=9398133 prefilling_elapsed_millis=4.007s
prefilling_size=9437925 prefilling_elapsed_millis=4.107s
prefilling_size=9473515 prefilling_elapsed_millis=4.207s
prefilling_size=9508455 prefilling_elapsed_millis=4.307s
prefilling_size=9541073 prefilling_elapsed_millis=4.408s
prefilling_size=9572390 prefilling_elapsed_millis=4.508s
prefilling_size=9600043 prefilling_elapsed_millis=4.608s
prefilling_size=9627140 prefilling_elapsed_millis=4.708s
prefilling_size=9651730 prefilling_elapsed_millis=4.808s
prefilling_size=9675540 prefilling_elapsed_millis=4.909s
prefilling_size=9696529 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 303438 keys with 48 threads...
tid=0 thread_prefill_amount=220798 percent_done_estimate=3492.7 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0394006s
prefilling_size=10000000 prefilling_elapsed_millis=0.039s
finished prefilling to size 10000000 for expected size 10000000 keysum=99989712375776, performing 71422978 updates; total_prefilling_elapsed_ms=39 ms)
pref_size=10000000
pref_millis=39
prefill_elapsed_ms=39
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6978998.800158147
REALTIME_END_PERF_FORMAT=6979008.800240436

###############################################################################
################################## 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=10000348
tree_stats_avgDegreeAtDepth=1.00003e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00003e+07
tree_stats_avgDegree=1.00003e+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=37870 38392 38285 38448 38141 37937 37922 38527 38700 38175 37927 37735 38392 38883 38841 38986 38143 37943 37912 38244 38500 38747 38192 38115 38038 38430 38214 38364 38291 37994 38097 38012 38481 38722 38138 38321 38257 38500 38727 38381 38258 37839 38208 38047 38069 38522 38064 37762
sum_num_inserts_total=1836693
sum_num_deletes_by_thread=38153 38446 38675 38479 38431 37771 38151 38167 38574 38435 37936 37802 38367 38225 38799 38488 38601 38053 37785 38190 38454 38854 38374 37810 38201 38105 38694 38623 38482 38170 37848 38381 38642 38734 38109 37903 38355 38428 38329 38461 38189 38277 38062 38231 38149 38540 38327 37703
sum_num_deletes_total=1837963
sum_num_searches_by_thread=7543876 7580291 7627119 7622362 7598107 7521150 7549639 7581435 7644764 7649455 7575788 7533335 7569622 7615926 7650670 7647322 7595180 7550988 7519724 7550783 7588241 7614995 7546733 7494539 7561307 7587752 7630897 7621645 7598547 7527955 7552084 7583707 7649103 7652405 7571867 7529891 7566984 7610482 7639308 7647760 7592487 7549986 7511889 7544037 7571510 7613875 7543405 7484459
sum_num_searches_total=363915386
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7619899 7657129 7704079 7699289 7674679 7596858 7625712 7658129 7722038 7726065 7651651 7608872 7646381 7693034 7728310 7724796 7671924 7626984 7595421 7627217 7665195 7692596 7623299 7570464 7637546 7664287 7707805 7698632 7675320 7604119 7628029 7660100 7726226 7729861 7648114 7606115 7643596 7687410 7716364 7724602 7668934 7626102 7588159 7620315 7647728 7690937 7619796 7559924
average_num_operations_total=7658125
stdev_num_operations_total=6672
sum_num_operations_total=367590042
min_num_operations_total=7559924
max_num_operations_total=7729861
first_time_thread_terminate_by_thread=10000138 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082
min_time_thread_terminate_total=10000082
max_time_thread_terminate_total=10000138
first_time_thread_start_by_thread=8 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 5 3 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 5
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99999168434736
threads_final_size=10000348
final_keysum=99999168434736
final_size=10000348
validate_result=success
Validation OK.
Structural validation OK.

total_find=363915386
total_rq=0
total_inserts=1836693
total_deletes=1837963
total_updates=3674656
total_queries=363915386
total_ops=367590042
find_throughput=36391538
rq_throughput=0
update_throughput=367465
query_throughput=36391538
total_throughput=36759004


total find                    : 363915386
total rq                      : 0
total inserts                 : 1836693
total deletes                 : 1837963
total updates                 : 3674656
total queries                 : 363915386
total ops                     : 367590042
find throughput               : 36391538
rq throughput                 : 0
update throughput             : 367465
query throughput              : 36391538
total throughput              : 36759004

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.7838
PAPI_L3_TCM=14.6162
PAPI_TOT_CYC=3631.69
PAPI_TOT_INS=777.797
garbage=1781478289171475
total_execution_walltime=15.631s
REALTIME_MAIN_END_PERF_FORMAT=6979009.111019008
[time_cmd_output] time_elapsed_sec=15.81, faults_major=0, faults_minor=265381, mem_maxresident_kb=1059572, user_cputime=731.98, sys_cputime=3.57, percent_cpu=4650%
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=1