__step=000027
__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=data000027.txt
__path_data=data/data000027.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979025.245215868
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.052s
prefilling_size=4092536 prefilling_elapsed_millis=1s
prefilling_size=4395476 prefilling_elapsed_millis=1.101s
prefilling_size=4682574 prefilling_elapsed_millis=1.201s
prefilling_size=4955771 prefilling_elapsed_millis=1.301s
prefilling_size=5214113 prefilling_elapsed_millis=1.402s
prefilling_size=5458975 prefilling_elapsed_millis=1.502s
prefilling_size=5689711 prefilling_elapsed_millis=1.602s
prefilling_size=5909902 prefilling_elapsed_millis=1.703s
prefilling_size=6118836 prefilling_elapsed_millis=1.803s
prefilling_size=6317502 prefilling_elapsed_millis=1.903s
prefilling_size=6507057 prefilling_elapsed_millis=2.004s
prefilling_size=6685398 prefilling_elapsed_millis=2.104s
prefilling_size=6854291 prefilling_elapsed_millis=2.204s
prefilling_size=7015282 prefilling_elapsed_millis=2.305s
prefilling_size=7167737 prefilling_elapsed_millis=2.405s
prefilling_size=7311379 prefilling_elapsed_millis=2.505s
prefilling_size=7449392 prefilling_elapsed_millis=2.606s
prefilling_size=7579971 prefilling_elapsed_millis=2.706s
prefilling_size=7703894 prefilling_elapsed_millis=2.806s
prefilling_size=7821147 prefilling_elapsed_millis=2.907s
prefilling_size=7932747 prefilling_elapsed_millis=3.007s
prefilling_size=8038034 prefilling_elapsed_millis=3.107s
prefilling_size=8137807 prefilling_elapsed_millis=3.208s
prefilling_size=8232086 prefilling_elapsed_millis=3.308s
prefilling_size=8321314 prefilling_elapsed_millis=3.408s
prefilling_size=8407555 prefilling_elapsed_millis=3.509s
prefilling_size=8488613 prefilling_elapsed_millis=3.609s
prefilling_size=8565588 prefilling_elapsed_millis=3.709s
prefilling_size=8639061 prefilling_elapsed_millis=3.81s
prefilling_size=8707528 prefilling_elapsed_millis=3.91s
prefilling_size=8774844 prefilling_elapsed_millis=4.01s
prefilling_size=8837340 prefilling_elapsed_millis=4.111s
prefilling_size=8896195 prefilling_elapsed_millis=4.211s
prefilling_size=8953074 prefilling_elapsed_millis=4.311s
prefilling_size=9007070 prefilling_elapsed_millis=4.412s
prefilling_size=9057152 prefilling_elapsed_millis=4.512s
prefilling_size=9105582 prefilling_elapsed_millis=4.612s
prefilling_size=9151322 prefilling_elapsed_millis=4.712s
prefilling_size=9194519 prefilling_elapsed_millis=4.813s
prefilling_size=9235453 prefilling_elapsed_millis=4.913s
prefilling_size=9273141 prefilling_elapsed_millis=5.013s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 726802 keys with 96 threads...
tid=0 thread_prefill_amount=89569 percent_done_estimate=1183.1 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=91652 percent_done_estimate=1210.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=93735 percent_done_estimate=1238.1 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.080964s
prefilling_size=10000000 prefilling_elapsed_millis=0.081s
finished prefilling to size 10000000 for expected size 10000000 keysum=100005843190026, performing 54049871 updates; total_prefilling_elapsed_ms=81 ms)
pref_size=10000000
pref_millis=81
prefill_elapsed_ms=81
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979030.672163497
REALTIME_END_PERF_FORMAT=6979040.672253698

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9998732
tree_stats_avgDegreeAtDepth=9.99873e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99873e+06
tree_stats_avgDegree=9.99873e+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=38952 39480 39528 39612 39589 39325 39185 39188 39266 40173 39549 38661 39314 39602 39865 39853 39373 38869 38723 39067 39395 39465 39166 38972 39138 39265 39592 39779 39319 38939 39291 39213 39753 40047 39326 39337 39533 39963 39901 39380 39675 39426 39109 38952 39359 39745 39224 39014 39773 39581 40234 39869 40038 39962 39788 39863 40243 40437 40042 39639 40276 40098 40290 40517 40032 39402 39894 39487 40225 40124 39958 39480 39571 39646 39912 40184 39742 39616 39579 39739 40336 40345 40165 39544 39944 40005 40313 40106 40370 39673 39461 39195 40091 39944 39623 39381
sum_num_inserts_total=3806189
sum_num_deletes_by_thread=39050 39628 39489 39299 39624 39023 38936 39602 39399 39691 39458 39376 39321 39480 39815 39911 38939 39330 39001 39175 39514 39558 39755 38932 39400 39182 39671 39636 39439 38938 39516 39257 39803 40115 39301 39163 39367 39719 39721 39979 39452 38966 38687 39148 39391 39722 38996 38948 39435 39847 40137 39900 40032 39918 39895 39775 40285 40598 40196 39598 39874 39965 40183 40041 39811 39648 39008 39533 40261 39991 39748 39387 39465 39790 40256 40065 39864 39043 39717 39611 40464 40652 40140 39837 40233 39740 40159 40117 39749 39839 39442 39536 40130 39675 39423 39400
sum_num_deletes_total=3804236
sum_num_searches_by_thread=7729004 7791192 7841376 7840551 7825271 7740109 7750776 7783232 7877736 7881612 7784198 7719400 7777496 7827050 7881123 7879919 7816132 7767240 7709513 7751803 7797853 7845236 7755544 7697695 7733314 7779719 7839414 7847135 7829869 7749017 7760120 7791312 7878338 7884066 7791631 7735103 7789916 7830435 7873721 7877161 7819423 7776836 7718696 7762080 7805462 7851340 7764336 7693333 7843739 7902729 7940292 7953300 7900599 7843352 7849719 7885748 7973018 7991701 7928412 7875154 7886758 7920995 7979725 7981350 7928272 7835619 7800301 7831437 7936526 7934880 7878769 7814861 7848900 7896846 7938088 7956695 7897027 7843601 7847657 7878381 7970842 7987161 7928677 7879081 7884029 7918380 7980826 7975785 7927423 7831920 7789488 7823876 7923602 7929359 7861858 7807636
sum_num_searches_total=753397232
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7807006 7870300 7920393 7919462 7904484 7818457 7828897 7862022 7956401 7961476 7863205 7797437 7856131 7906132 7960803 7959683 7894444 7845439 7787237 7830045 7876762 7924259 7834465 7775599 7811852 7858166 7918677 7926550 7908627 7826894 7838927 7869782 7957894 7964228 7870258 7813603 7868816 7910117 7953343 7956520 7898550 7855228 7796492 7840180 7884212 7930807 7842556 7771295 7922947 7982157 8020663 8033069 7980669 7923232 7929402 7965386 8053546 8072736 8008650 7954391 7966908 8001058 8060198 8061908 8008115 7914669 7879203 7910457 8017012 8014995 7958475 7893728 7927936 7976282 8018256 8036944 7976633 7922260 7926953 7957731 8051642 8068158 8008982 7958462 7964206 7998125 8061298 8056008 8007542 7911432 7868391 7902607 8003823 8008978 7940904 7886417
average_num_operations_total=7927163
stdev_num_operations_total=7807
sum_num_operations_total=761007657
min_num_operations_total=7771295
max_num_operations_total=8072736
first_time_thread_terminate_by_thread=10000161 10000090 10000089 10000090 10000091 10000089 10000089 10000089 10000089 10000091 10000089 10000090 10000090 10000092 10000092 10000090 10000092 10000090 10000090 10000089 10000092 10000090 10000091 10000091 10000090 10000090 10000089 10000091 10000091 10000089 10000089 10000089 10000089 10000091 10000089 10000090 10000090 10000092 10000092 10000090 10000092 10000090 10000090 10000089 10000092 10000090 10000091 10000090 10000091 10000090 10000091 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000091 10000091 10000089 10000089 10000091 10000090 10000090 10000090 10000090 10000091 10000090 10000091 10000090 10000091 10000091 10000090 10000091 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000091 10000091 10000089 10000089 10000091 10000090 10000090 10000090 10000090 10000091 10000090 10000091 10000090 10000091
min_time_thread_terminate_total=10000089
max_time_thread_terminate_total=10000161
first_time_thread_start_by_thread=9 5 5 5 6 5 6 6 5 5 6 6 5 5 6 6 5 5 6 5 6 6 5 5 5 5 5 5 6 5 6 6 5 5 6 6 5 5 6 6 5 5 6 5 6 6 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99994226305092
threads_final_size=9998732
final_keysum=99994226305092
final_size=9998732
validate_result=success
Validation OK.
Structural validation OK.

total_find=753397232
total_rq=0
total_inserts=3806189
total_deletes=3804236
total_updates=7610425
total_queries=753397232
total_ops=761007657
find_throughput=75339723
rq_throughput=0
update_throughput=761042
query_throughput=75339723
total_throughput=76100765


total find                    : 753397232
total rq                      : 0
total inserts                 : 3806189
total deletes                 : 3804236
total updates                 : 7610425
total queries                 : 753397232
total ops                     : 761007657
find throughput               : 75339723
rq throughput                 : 0
update throughput             : 761042
query throughput              : 75339723
total throughput              : 76100765

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.849
PAPI_L3_TCM=15.1535
PAPI_TOT_CYC=3440.36
PAPI_TOT_INS=781.453
garbage=3689307320079481
total_execution_walltime=15.708s
REALTIME_MAIN_END_PERF_FORMAT=6979040.954834916
[time_cmd_output] time_elapsed_sec=15.90, faults_major=0, faults_minor=310941, mem_maxresident_kb=1236764, user_cputime=1465.29, sys_cputime=5.14, percent_cpu=9242%
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