__step=000363
__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 96 -nprefill 96 -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=data000363.txt
__path_data=data/data000363.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983149.220133155
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.011s
prefilling_size=3789238 prefilling_elapsed_millis=1.001s
prefilling_size=4078635 prefilling_elapsed_millis=1.101s
prefilling_size=4356905 prefilling_elapsed_millis=1.201s
prefilling_size=4619778 prefilling_elapsed_millis=1.301s
prefilling_size=4871271 prefilling_elapsed_millis=1.402s
prefilling_size=5112392 prefilling_elapsed_millis=1.502s
prefilling_size=5340835 prefilling_elapsed_millis=1.602s
prefilling_size=5559310 prefilling_elapsed_millis=1.702s
prefilling_size=5769586 prefilling_elapsed_millis=1.803s
prefilling_size=5967188 prefilling_elapsed_millis=1.903s
prefilling_size=6157144 prefilling_elapsed_millis=2.003s
prefilling_size=6336638 prefilling_elapsed_millis=2.103s
prefilling_size=6509675 prefilling_elapsed_millis=2.203s
prefilling_size=6671957 prefilling_elapsed_millis=2.304s
prefilling_size=6829634 prefilling_elapsed_millis=2.404s
prefilling_size=6977748 prefilling_elapsed_millis=2.504s
prefilling_size=7119046 prefilling_elapsed_millis=2.604s
prefilling_size=7253587 prefilling_elapsed_millis=2.704s
prefilling_size=7382040 prefilling_elapsed_millis=2.805s
prefilling_size=7504566 prefilling_elapsed_millis=2.905s
prefilling_size=7621760 prefilling_elapsed_millis=3.005s
prefilling_size=7733940 prefilling_elapsed_millis=3.105s
prefilling_size=7839883 prefilling_elapsed_millis=3.205s
prefilling_size=7940713 prefilling_elapsed_millis=3.306s
prefilling_size=8037896 prefilling_elapsed_millis=3.406s
prefilling_size=8128856 prefilling_elapsed_millis=3.506s
prefilling_size=8215285 prefilling_elapsed_millis=3.606s
prefilling_size=8297721 prefilling_elapsed_millis=3.706s
prefilling_size=8377141 prefilling_elapsed_millis=3.807s
prefilling_size=8453271 prefilling_elapsed_millis=3.907s
prefilling_size=8525077 prefilling_elapsed_millis=4.007s
prefilling_size=8593621 prefilling_elapsed_millis=4.107s
prefilling_size=8657858 prefilling_elapsed_millis=4.207s
prefilling_size=8720638 prefilling_elapsed_millis=4.308s
prefilling_size=8780893 prefilling_elapsed_millis=4.408s
prefilling_size=8837537 prefilling_elapsed_millis=4.508s
prefilling_size=8892076 prefilling_elapsed_millis=4.608s
prefilling_size=8943251 prefilling_elapsed_millis=4.708s
prefilling_size=8993256 prefilling_elapsed_millis=4.809s
prefilling_size=9040303 prefilling_elapsed_millis=4.909s
prefilling_size=9085870 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 914085 keys with 96 threads...
tid=0 thread_prefill_amount=97901 percent_done_estimate=1028.2 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=99984 percent_done_estimate=1050.1 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=102067 percent_done_estimate=1071.9 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=104150 percent_done_estimate=1093.8 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=106233 percent_done_estimate=1115.7 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.118268s
prefilling_size=10000000 prefilling_elapsed_millis=0.118s
finished prefilling to size 10000000 for expected size 10000000 keysum=99985079017254, performing 49655736 updates; total_prefilling_elapsed_ms=118 ms)
pref_size=10000000
pref_millis=118
prefill_elapsed_ms=118
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983154.644156099
REALTIME_END_PERF_FORMAT=6983164.644244498

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9992609
tree_stats_avgDegreeAtDepth=9.99261e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99261e+06
tree_stats_avgDegree=9.99261e+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=219013 221185 222081 219827 219820 216258 219863 221323 222137 220177 219087 218453 221237 221978 223671 221318 219702 218179 220439 221064 221460 219921 217191 217227 220185 219988 222482 220111 220171 218211 220526 221132 223013 219796 218452 218426 220527 222230 222873 220485 218104 218640 220223 220760 222481 220392 218217 217533 220488 220199 221329 219955 218137 216967 220419 220474 223000 220213 218383 218480 221122 221022 223017 220253 218806 215874 219034 218246 221520 219530 218637 217597 219556 221176 221223 219278 218580 217065 219952 219625 222953 220447 219031 218144 219971 220112 222952 219267 219380 217186 218389 217646 221120 219551 217716 217549
sum_num_inserts_total=21108140
sum_num_deletes_by_thread=219912 221117 221748 219899 221170 216803 221312 221358 222694 220391 219138 218554 220385 221583 223843 220197 219271 219101 219776 219139 222769 220088 219006 216837 219793 220714 221668 218081 218833 217612 221968 220641 222577 219787 218359 217827 220129 222560 222664 220479 219217 218666 220453 220219 222595 220601 218397 217398 219819 220749 221703 219025 218166 217751 220309 221363 223244 219682 219807 218513 221098 222533 223085 220558 218001 216833 218444 218349 221001 220556 217911 216679 220245 220627 220184 219419 218597 216860 219606 220388 223134 219303 218609 217825 221585 220771 222595 218880 218280 216243 217642 217636 221522 218260 217991 217771
sum_num_deletes_total=21106481
sum_num_searches_by_thread=3953433 3968122 3989458 3945847 3953040 3920679 3971735 3977175 4007727 3965205 3941252 3925084 3977040 4004611 4012337 3975537 3942975 3930725 3966181 3972028 3999429 3965889 3928030 3915266 3961586 3972635 3992851 3946494 3955053 3913448 3965977 3975751 4014215 3960072 3940781 3920601 3977015 3998913 4014837 3966099 3943802 3933605 3963056 3971338 4003957 3964348 3928471 3921481 3962543 3974820 3987174 3947590 3936557 3912651 3968690 3969287 4018355 3959925 3943291 3925882 3982473 3983223 4019436 3957031 3932870 3900694 3930114 3922585 3985306 3950472 3930927 3912040 3956546 3961081 3975456 3945041 3930703 3902937 3958917 3960702 4008968 3955115 3940286 3925831 3972652 3982383 4007600 3948769 3933638 3896485 3932165 3917098 3981912 3948666 3922290 3906714
sum_num_searches_total=379901082
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4392358 4410424 4433287 4385573 4394030 4353740 4412910 4419856 4452558 4405773 4379477 4362091 4418662 4448172 4459851 4417052 4381948 4368005 4406396 4412231 4443658 4405898 4364227 4349330 4401564 4413337 4437001 4384686 4394057 4349271 4408471 4417524 4459805 4399655 4377592 4356854 4417671 4443703 4460374 4407063 4381123 4370911 4403732 4412317 4449033 4405341 4365085 4356412 4402850 4415768 4430206 4386570 4372860 4347369 4409418 4411124 4464599 4399820 4381481 4362875 4424693 4426778 4465538 4397842 4369677 4333401 4367592 4359180 4427827 4390558 4367475 4346316 4396347 4402884 4416863 4383738 4367880 4336862 4398475 4400715 4455055 4394865 4377926 4361800 4414208 4423266 4453147 4386916 4371298 4329914 4368196 4352380 4424554 4386477 4357997 4342034
average_num_operations_total=4397038
stdev_num_operations_total=3397
sum_num_operations_total=422115703
min_num_operations_total=4329914
max_num_operations_total=4465538
first_time_thread_terminate_by_thread=10000164 10000088 10000088 10000088 10000088 10000088 10000089 10000088 10000088 10000088 10000088 10000089 10000088 10000090 10000089 10000089 10000090 10000091 10000088 10000091 10000090 10000087 10000088 10000088 10000088 10000088 10000088 10000088 10000088 10000088 10000089 10000088 10000088 10000089 10000088 10000089 10000090 10000090 10000089 10000089 10000090 10000089 10000088 10000089 10000090 10000087 10000088 10000089 10000088 10000088 10000089 10000088 10000088 10000088 10000088 10000088 10000089 10000090 10000089 10000090 10000090 10000088 10000090 10000089 10000089 10000090 10000090 10000088 10000088 10000088 10000089 10000090 10000088 10000088 10000090 10000088 10000088 10000088 10000088 10000088 10000089 10000090 10000089 10000090 10000090 10000088 10000090 10000089 10000089 10000090 10000090 10000088 10000090 10000088 10000089 10000090
min_time_thread_terminate_total=10000087
max_time_thread_terminate_total=10000164
first_time_thread_start_by_thread=9 4 5 5 4 4 5 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 3 4 5 5 4 4 5 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 5 5 5 5 4 4 5 5 5 5 5 4 4 5 5 4 5 4 4 5 5 5 4 5 5 5 5 5 4 4 5 5 5 5 5 4 4 5 5 4 5 4 4 5 5 5 4 5
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99916424700177
threads_final_size=9992609
final_keysum=99916424700177
final_size=9992609
validate_result=success
Validation OK.
Structural validation OK.

total_find=379901082
total_rq=0
total_inserts=21108140
total_deletes=21106481
total_updates=42214621
total_queries=379901082
total_ops=422115703
find_throughput=37990108
rq_throughput=0
update_throughput=4221462
query_throughput=37990108
total_throughput=42211570


total find                    : 379901082
total rq                      : 0
total inserts                 : 21108140
total deletes                 : 21106481
total updates                 : 42214621
total queries                 : 379901082
total ops                     : 422115703
find throughput               : 37990108
rq throughput                 : 0
update throughput             : 4221462
query throughput              : 37990108
total throughput              : 42211570

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.3393
PAPI_L3_TCM=16.4965
PAPI_TOT_CYC=6323.03
PAPI_TOT_INS=832.274
garbage=1879910736542702
total_execution_walltime=15.721s
REALTIME_MAIN_END_PERF_FORMAT=6983164.942092940
[time_cmd_output] time_elapsed_sec=15.94, faults_major=0, faults_minor=321822, mem_maxresident_kb=1253692, user_cputime=1465.92, sys_cputime=6.28, percent_cpu=9233%
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