__step=000028
__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 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=data000028.txt
__path_data=data/data000028.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979041.212431010
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,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.043s
prefilling_size=4135247 prefilling_elapsed_millis=1.001s
prefilling_size=4439423 prefilling_elapsed_millis=1.101s
prefilling_size=4729635 prefilling_elapsed_millis=1.201s
prefilling_size=5001585 prefilling_elapsed_millis=1.301s
prefilling_size=5260088 prefilling_elapsed_millis=1.402s
prefilling_size=5505767 prefilling_elapsed_millis=1.502s
prefilling_size=5738039 prefilling_elapsed_millis=1.602s
prefilling_size=5958861 prefilling_elapsed_millis=1.702s
prefilling_size=6168204 prefilling_elapsed_millis=1.802s
prefilling_size=6367627 prefilling_elapsed_millis=1.903s
prefilling_size=6553587 prefilling_elapsed_millis=2.003s
prefilling_size=6731845 prefilling_elapsed_millis=2.103s
prefilling_size=6901519 prefilling_elapsed_millis=2.203s
prefilling_size=7062120 prefilling_elapsed_millis=2.303s
prefilling_size=7214206 prefilling_elapsed_millis=2.404s
prefilling_size=7357137 prefilling_elapsed_millis=2.504s
prefilling_size=7493721 prefilling_elapsed_millis=2.604s
prefilling_size=7622011 prefilling_elapsed_millis=2.704s
prefilling_size=7745349 prefilling_elapsed_millis=2.804s
prefilling_size=7862493 prefilling_elapsed_millis=2.905s
prefilling_size=7972651 prefilling_elapsed_millis=3.005s
prefilling_size=8077379 prefilling_elapsed_millis=3.105s
prefilling_size=8177311 prefilling_elapsed_millis=3.205s
prefilling_size=8271890 prefilling_elapsed_millis=3.305s
prefilling_size=8361109 prefilling_elapsed_millis=3.405s
prefilling_size=8445409 prefilling_elapsed_millis=3.506s
prefilling_size=8525849 prefilling_elapsed_millis=3.606s
prefilling_size=8601648 prefilling_elapsed_millis=3.706s
prefilling_size=8675080 prefilling_elapsed_millis=3.806s
prefilling_size=8743140 prefilling_elapsed_millis=3.906s
prefilling_size=8807383 prefilling_elapsed_millis=4.007s
prefilling_size=8868555 prefilling_elapsed_millis=4.107s
prefilling_size=8927198 prefilling_elapsed_millis=4.207s
prefilling_size=8982339 prefilling_elapsed_millis=4.307s
prefilling_size=9033982 prefilling_elapsed_millis=4.407s
prefilling_size=9084585 prefilling_elapsed_millis=4.508s
prefilling_size=9132119 prefilling_elapsed_millis=4.608s
prefilling_size=9176115 prefilling_elapsed_millis=4.708s
prefilling_size=9219066 prefilling_elapsed_millis=4.808s
prefilling_size=9259228 prefilling_elapsed_millis=4.908s
prefilling_size=9297103 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 702889 keys with 96 threads...
tid=0 thread_prefill_amount=95818 percent_done_estimate=1308.7 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=97901 percent_done_estimate=1337.1 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=99984 percent_done_estimate=1365.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=102067 percent_done_estimate=1394.0 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0796377s
prefilling_size=10000000 prefilling_elapsed_millis=0.079s
finished prefilling to size 10000000 for expected size 10000000 keysum=100013550934847, performing 54646077 updates; total_prefilling_elapsed_ms=79 ms)
pref_size=10000000
pref_millis=79
prefill_elapsed_ms=80
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979046.644160828
REALTIME_END_PERF_FORMAT=6979056.644249843

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9996218
tree_stats_avgDegreeAtDepth=9.99622e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99622e+06
tree_stats_avgDegree=9.99622e+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=39592 39926 39881 39932 39995 39431 39803 39687 40253 39749 39251 39116 39474 40034 40360 40062 40054 39606 39325 39920 39863 39815 39592 39329 39414 39935 39827 40245 39779 39580 39561 39562 40095 39924 39737 39616 39839 40038 39872 40447 39713 39697 39492 39588 39653 40021 39553 39434 39340 39840 39915 39473 40009 39296 39447 39760 40063 39866 39603 39514 39460 39817 40150 40183 39793 38972 39177 39738 39944 39523 39330 39175 39847 39889 40174 40150 39586 39268 39473 39488 40032 39584 39621 39832 39665 39633 40407 39977 39900 39201 39235 39311 39700 39706 39380 39329
sum_num_inserts_total=3812448
sum_num_deletes_by_thread=39517 39690 40041 40235 40059 39664 40051 39805 40309 40430 39595 39540 39810 39966 40352 40321 39793 39778 39171 39655 39764 40024 39134 39466 39491 39996 40065 40325 39839 39072 39636 39726 40092 40208 39733 39033 39992 40217 40304 39994 39761 39560 39212 39559 39632 39754 39507 39163 39694 39218 40062 39888 39672 39208 39544 39435 40136 39835 39721 39270 39584 39762 40700 40434 40021 39627 39137 39203 40284 39708 39693 39260 39398 39472 40220 39829 39478 39381 39773 39724 40301 39967 39782 39585 39986 39885 40205 40143 40016 39134 39132 39312 39777 39857 39638 39368
sum_num_deletes_total=3816500
sum_num_searches_by_thread=7825845 7878949 7921181 7908738 7892478 7802361 7834699 7875344 7961020 7943112 7840312 7795059 7854382 7919263 7974169 7968354 7895793 7840493 7800193 7838813 7876625 7895808 7814622 7760847 7836135 7884412 7922095 7901787 7899699 7804872 7837384 7873422 7964767 7941772 7838920 7791168 7858373 7915300 7959772 7957318 7888304 7839807 7794415 7833990 7853430 7896456 7810855 7746316 7824868 7874525 7917283 7890452 7854173 7785268 7821484 7863392 7940487 7928942 7855518 7802772 7846823 7899687 7954090 7944865 7886514 7797487 7770250 7808501 7912024 7895539 7830757 7779474 7822708 7870666 7910575 7898671 7853407 7792370 7818432 7860777 7935938 7927121 7857556 7810881 7847686 7899452 7955509 7937921 7881733 7795257 7765979 7798122 7904166 7886991 7816138 7766658
sum_num_searches_total=755069118
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7904954 7958565 8001103 7988905 7972532 7881456 7914553 7954836 8041582 8023291 7919158 7873715 7933666 7999263 8054881 8048737 7975640 7919877 7878689 7918388 7956252 7975647 7893348 7839642 7915040 7964343 8001987 7982357 7979317 7883524 7916581 7952710 8044954 8021904 7918390 7869817 7938204 7995555 8039948 8037759 7967778 7919064 7873119 7913137 7932715 7976231 7889915 7824913 7903902 7953583 7997260 7969813 7933854 7863772 7900475 7942587 8020686 8008643 7934842 7881556 7925867 7979266 8034940 8025482 7966328 7876086 7848564 7887442 7992252 7974770 7909780 7857909 7901953 7950027 7990969 7978650 7932471 7871019 7897678 7939989 8016271 8006672 7936959 7890298 7927337 7978970 8036121 8018041 7961649 7873592 7844346 7876745 7983643 7966554 7895156 7845355
average_num_operations_total=7944771
stdev_num_operations_total=5857
sum_num_operations_total=762698066
min_num_operations_total=7824913
max_num_operations_total=8054881
first_time_thread_terminate_by_thread=10000162 10000089 10000088 10000089 10000089 10000088 10000090 10000090 10000090 10000088 10000090 10000089 10000089 10000089 10000088 10000089 10000089 10000089 10000089 10000089 10000089 10000089 10000088 10000089 10000089 10000089 10000088 10000089 10000089 10000088 10000090 10000089 10000090 10000088 10000088 10000089 10000089 10000089 10000088 10000089 10000089 10000089 10000089 10000089 10000089 10000089 10000088 10000089 10000087 10000088 10000087 10000087 10000087 10000088 10000087 10000087 10000087 10000087 10000087 10000087 10000087 10000089 10000087 10000087 10000087 10000088 10000087 10000087 10000087 10000087 10000088 10000088 10000087 10000088 10000087 10000087 10000087 10000088 10000088 10000087 10000087 10000087 10000087 10000087 10000087 10000089 10000087 10000087 10000087 10000088 10000087 10000087 10000087 10000087 10000088 10000088
min_time_thread_terminate_total=10000087
max_time_thread_terminate_total=10000162
first_time_thread_start_by_thread=9 5 5 4 4 5 5 5 4 4 4 5 4 4 5 5 4 5 4 4 4 4 4 4 4 5 5 4 4 5 5 5 4 4 4 5 4 4 5 5 4 5 4 4 4 4 4 4 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99976112730969
threads_final_size=9996218
final_keysum=99976112730969
final_size=9996218
validate_result=success
Validation OK.
Structural validation OK.

total_find=755069118
total_rq=0
total_inserts=3812448
total_deletes=3816500
total_updates=7628948
total_queries=755069118
total_ops=762698066
find_throughput=75506911
rq_throughput=0
update_throughput=762894
query_throughput=75506911
total_throughput=76269806


total find                    : 755069118
total rq                      : 0
total inserts                 : 3812448
total deletes                 : 3816500
total updates                 : 7628948
total queries                 : 755069118
total ops                     : 762698066
find throughput               : 75506911
rq throughput                 : 0
update throughput             : 762894
query throughput              : 75506911
total throughput              : 76269806

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=35.0126
PAPI_L3_TCM=15.1635
PAPI_TOT_CYC=3428.54
PAPI_TOT_INS=785.63
garbage=3700789287867050
total_execution_walltime=15.714s
REALTIME_MAIN_END_PERF_FORMAT=6979056.927382803
[time_cmd_output] time_elapsed_sec=15.92, faults_major=0, faults_minor=321317, mem_maxresident_kb=1276880, user_cputime=1464.60, sys_cputime=5.77, percent_cpu=9236%
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