__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 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=data000361.txt
__path_data=data/data000361.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983117.380448163
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.004s
prefilling_size=5188850 prefilling_elapsed_millis=1.001s
prefilling_size=5515361 prefilling_elapsed_millis=1.101s
prefilling_size=5817237 prefilling_elapsed_millis=1.201s
prefilling_size=6099535 prefilling_elapsed_millis=1.301s
prefilling_size=6361379 prefilling_elapsed_millis=1.402s
prefilling_size=6604849 prefilling_elapsed_millis=1.502s
prefilling_size=6832097 prefilling_elapsed_millis=1.602s
prefilling_size=7041868 prefilling_elapsed_millis=1.702s
prefilling_size=7236874 prefilling_elapsed_millis=1.802s
prefilling_size=7419977 prefilling_elapsed_millis=1.903s
prefilling_size=7590840 prefilling_elapsed_millis=2.003s
prefilling_size=7746815 prefilling_elapsed_millis=2.103s
prefilling_size=7894342 prefilling_elapsed_millis=2.203s
prefilling_size=8030372 prefilling_elapsed_millis=2.303s
prefilling_size=8156068 prefilling_elapsed_millis=2.404s
prefilling_size=8274442 prefilling_elapsed_millis=2.504s
prefilling_size=8384746 prefilling_elapsed_millis=2.604s
prefilling_size=8488836 prefilling_elapsed_millis=2.704s
prefilling_size=8585258 prefilling_elapsed_millis=2.804s
prefilling_size=8674914 prefilling_elapsed_millis=2.905s
prefilling_size=8759867 prefilling_elapsed_millis=3.005s
prefilling_size=8838935 prefilling_elapsed_millis=3.105s
prefilling_size=8912682 prefilling_elapsed_millis=3.205s
prefilling_size=8980392 prefilling_elapsed_millis=3.305s
prefilling_size=9044863 prefilling_elapsed_millis=3.406s
prefilling_size=9103768 prefilling_elapsed_millis=3.506s
prefilling_size=9158949 prefilling_elapsed_millis=3.606s
prefilling_size=9209938 prefilling_elapsed_millis=3.706s
prefilling_size=9258456 prefilling_elapsed_millis=3.806s
prefilling_size=9303765 prefilling_elapsed_millis=3.906s
prefilling_size=9346053 prefilling_elapsed_millis=4.007s
prefilling_size=9385724 prefilling_elapsed_millis=4.107s
prefilling_size=9424930 prefilling_elapsed_millis=4.207s
prefilling_size=9459857 prefilling_elapsed_millis=4.307s
prefilling_size=9492503 prefilling_elapsed_millis=4.407s
prefilling_size=9523529 prefilling_elapsed_millis=4.508s
prefilling_size=9553248 prefilling_elapsed_millis=4.608s
prefilling_size=9579178 prefilling_elapsed_millis=4.708s
prefilling_size=9605228 prefilling_elapsed_millis=4.808s
prefilling_size=9628875 prefilling_elapsed_millis=4.909s
prefilling_size=9650402 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 349543 keys with 48 threads...
tid=0 thread_prefill_amount=170806 percent_done_estimate=2345.5 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=174972 percent_done_estimate=2402.8 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0398707s
prefilling_size=10000000 prefilling_elapsed_millis=0.04s
finished prefilling to size 10000000 for expected size 10000000 keysum=99983165063397, performing 72946375 updates; total_prefilling_elapsed_ms=40 ms)
pref_size=10000000
pref_millis=40
prefill_elapsed_ms=40
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983122.734477662
REALTIME_END_PERF_FORMAT=6983132.734563765

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9999708
tree_stats_avgDegreeAtDepth=9.99971e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99971e+06
tree_stats_avgDegree=9.99971e+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=332903 335309 336076 336431 336754 333454 332030 334441 338348 336832 334729 332715 333485 335836 337082 338703 335602 333143 331744 333045 335451 336061 333206 331778 333193 335038 336497 335154 336528 332312 333507 335145 337523 337553 334516 331862 334722 336352 337766 337526 335153 333278 330516 333374 334191 335746 333408 331719
sum_num_inserts_total=16067737
sum_num_deletes_by_thread=333713 335073 335957 336407 337643 333456 334279 334859 336008 337980 334053 332508 333257 336575 336776 337867 335008 334896 331602 333130 334703 337264 333697 331308 332981 333412 335682 335877 334826 333395 334221 334741 336918 337435 335553 332230 333612 336492 337819 337861 335833 332436 331020 333590 335113 336153 332977 332307
sum_num_deletes_total=16070503
sum_num_searches_by_thread=5993704 6020727 6050462 6042875 6048837 5990754 6007325 6019967 6071274 6079900 6017733 5989900 6023114 6050951 6078263 6076947 6035962 6009391 5964789 6002257 6028033 6055534 6009970 5964172 6001498 6026255 6054602 6052215 6049235 5994748 6007215 6019020 6062029 6077727 6015932 5987117 6016212 6051802 6071875 6081637 6035750 6002158 5973121 6002331 6026911 6058053 6009386 5963337
sum_num_searches_total=289273007
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6660320 6691109 6722495 6715713 6723234 6657664 6673634 6689267 6745630 6754712 6686515 6655123 6689856 6723362 6752121 6753517 6706572 6677430 6628135 6668432 6698187 6728859 6676873 6627258 6667672 6694705 6726781 6723246 6720589 6660455 6674943 6688906 6736470 6752715 6686001 6651209 6684546 6724646 6747460 6757024 6706736 6667872 6634657 6669295 6696215 6729952 6675771 6627363
average_num_operations_total=6696067
stdev_num_operations_total=5334
sum_num_operations_total=321411247
min_num_operations_total=6627258
max_num_operations_total=6757024
first_time_thread_terminate_by_thread=10000140 10000086 10000086 10000086 10000086 10000086 10000086 10000085 10000086 10000087 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000087 10000086 10000085 10000085 10000085 10000086 10000086 10000085 10000086 10000086 10000086 10000087 10000086 10000086 10000085 10000086 10000085 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000087 10000086 10000086 10000085 10000085 10000086 10000086
min_time_thread_terminate_total=10000085
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=9 6 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 6 6 5 6 6 4 6 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 6 6 5 6 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99992307446996
threads_final_size=9999708
final_keysum=99992307446996
final_size=9999708
validate_result=success
Validation OK.
Structural validation OK.

total_find=289273007
total_rq=0
total_inserts=16067737
total_deletes=16070503
total_updates=32138240
total_queries=289273007
total_ops=321411247
find_throughput=28927300
rq_throughput=0
update_throughput=3213824
query_throughput=28927300
total_throughput=32141124


total find                    : 289273007
total rq                      : 0
total inserts                 : 16067737
total deletes                 : 16070503
total updates                 : 32138240
total queries                 : 289273007
total ops                     : 321411247
find throughput               : 28927300
rq throughput                 : 0
update throughput             : 3213824
query throughput              : 28927300
total throughput              : 32141124

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.2204
PAPI_L3_TCM=15.7352
PAPI_TOT_CYC=4136.86
PAPI_TOT_INS=837.705
garbage=1446337235721324
total_execution_walltime=15.66s
REALTIME_MAIN_END_PERF_FORMAT=6983133.55165444
[time_cmd_output] time_elapsed_sec=15.85, faults_major=0, faults_minor=276966, mem_maxresident_kb=1091328, user_cputime=732.26, sys_cputime=3.37, percent_cpu=4640%
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