__step=000701
__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 144 -nprefill 144 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000701.txt
__path_data=data/data000701.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987308.123817816
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=50
DEL_FRAC=50
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=50.0 50.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,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.057s
prefilling_size=3627961 prefilling_elapsed_millis=1.001s
prefilling_size=3908715 prefilling_elapsed_millis=1.101s
prefilling_size=4183747 prefilling_elapsed_millis=1.202s
prefilling_size=4439533 prefilling_elapsed_millis=1.303s
prefilling_size=4690217 prefilling_elapsed_millis=1.403s
prefilling_size=4923298 prefilling_elapsed_millis=1.504s
prefilling_size=5152256 prefilling_elapsed_millis=1.604s
prefilling_size=5364492 prefilling_elapsed_millis=1.705s
prefilling_size=5573560 prefilling_elapsed_millis=1.805s
prefilling_size=5766695 prefilling_elapsed_millis=1.906s
prefilling_size=5954053 prefilling_elapsed_millis=2.006s
prefilling_size=6131302 prefilling_elapsed_millis=2.107s
prefilling_size=6302785 prefilling_elapsed_millis=2.207s
prefilling_size=6464525 prefilling_elapsed_millis=2.308s
prefilling_size=6621739 prefilling_elapsed_millis=2.408s
prefilling_size=6768447 prefilling_elapsed_millis=2.509s
prefilling_size=6909733 prefilling_elapsed_millis=2.609s
prefilling_size=7045063 prefilling_elapsed_millis=2.71s
prefilling_size=7175245 prefilling_elapsed_millis=2.81s
prefilling_size=7298570 prefilling_elapsed_millis=2.911s
prefilling_size=7416611 prefilling_elapsed_millis=3.011s
prefilling_size=7530128 prefilling_elapsed_millis=3.112s
prefilling_size=7639066 prefilling_elapsed_millis=3.212s
prefilling_size=7742370 prefilling_elapsed_millis=3.313s
prefilling_size=7841337 prefilling_elapsed_millis=3.413s
prefilling_size=7935261 prefilling_elapsed_millis=3.514s
prefilling_size=8025221 prefilling_elapsed_millis=3.614s
prefilling_size=8110041 prefilling_elapsed_millis=3.715s
prefilling_size=8191873 prefilling_elapsed_millis=3.815s
prefilling_size=8269499 prefilling_elapsed_millis=3.916s
prefilling_size=8344113 prefilling_elapsed_millis=4.016s
prefilling_size=8415343 prefilling_elapsed_millis=4.117s
prefilling_size=8483076 prefilling_elapsed_millis=4.217s
prefilling_size=8547866 prefilling_elapsed_millis=4.318s
prefilling_size=8609454 prefilling_elapsed_millis=4.419s
prefilling_size=8669247 prefilling_elapsed_millis=4.519s
prefilling_size=8727210 prefilling_elapsed_millis=4.62s
prefilling_size=8781615 prefilling_elapsed_millis=4.72s
prefilling_size=8833343 prefilling_elapsed_millis=4.821s
prefilling_size=8883219 prefilling_elapsed_millis=4.921s
prefilling_size=8931109 prefilling_elapsed_millis=5.022s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1068615 keys with 144 threads...
tid=0 thread_prefill_amount=56908 percent_done_estimate=766.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=58296 percent_done_estimate=785.6 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=59684 percent_done_estimate=804.3 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=61072 percent_done_estimate=823.0 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=62460 percent_done_estimate=841.7 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=63848 percent_done_estimate=860.4 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.153962s
prefilling_size=10000000 prefilling_elapsed_millis=0.154s
finished prefilling to size 10000000 for expected size 10000000 keysum=99986474453672, performing 48023817 updates; total_prefilling_elapsed_ms=154 ms)
pref_size=10000000
pref_millis=154
prefill_elapsed_ms=154
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987313.662340571
REALTIME_END_PERF_FORMAT=6987323.662500729

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10000151
tree_stats_avgDegreeAtDepth=1.00002e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00002e+07
tree_stats_avgDegree=1.00002e+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=307854 308335 310967 308347 306375 307015 308438 308793 311196 308374 308455 308217 308511 307673 311598 307415 307673 307911 308326 306883 308955 308142 308301 306808 308792 308072 310363 307272 306796 307166 309111 308434 311733 308947 309011 308164 307877 308057 311284 306366 308683 308462 307356 306082 308339 308008 308112 306630 337761 336168 340579 337048 336740 336458 338502 337462 342430 339311 339850 339213 339608 337846 340531 336635 336658 337823 337072 335756 338094 337714 338385 337208 337141 336780 340966 337165 336909 336662 338329 337299 341401 339382 338810 340112 339492 339254 337334 337614 336884 336580 338139 336673 339701 337973 337429 337802 319428 318815 320932 317825 317579 317549 318796 319922 321916 318430 319123 317603 319172 319331 321222 318073 316765 317131 318998 318525 317081 318059 318677 317130 318273 318910 321328 317965 317116 317051 318494 319261 321348 318805 317517 318111 319911 318498 320856 317424 317244 316579 319151 317852 317432 318052 318169 317405
sum_num_inserts_total=46321196
sum_num_deletes_by_thread=306797 308242 309131 307449 308065 307054 309627 307992 311814 307640 309956 308375 307830 307032 312257 306569 308261 307949 307478 306704 308497 307397 308329 307383 308131 307697 309828 307580 307581 306863 308709 308779 311580 306739 309595 307434 308426 306725 311479 307896 307818 307747 308305 306588 308367 307712 308264 307324 338430 337698 340618 337330 337811 336789 338401 338557 342331 339679 339470 339263 339543 339592 339492 337013 337447 337182 336961 336498 339452 337645 337481 337195 338141 338542 341542 336685 338413 336994 337698 338190 343030 339227 338377 338159 338501 337874 337892 336173 337684 337841 337215 335553 338744 337604 337658 337011 318113 317895 321351 317714 317271 317106 319168 319060 321540 318433 318709 317832 319450 318674 320691 316993 316513 317081 319142 317495 317950 318486 317820 317284 318692 318148 320283 317382 317759 318383 319444 320359 321661 318450 319800 318144 318832 318829 320962 318101 316747 317142 318498 317942 317529 318909 319163 317468
sum_num_deletes_total=46322019
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=614651 616577 620098 615796 614440 614069 618065 616785 623010 616014 618411 616592 616341 614705 623855 613984 615934 615860 615804 613587 617452 615539 616630 614191 616923 615769 620191 614852 614377 614029 617820 617213 623313 615686 618606 615598 616303 614782 622763 614262 616501 616209 615661 612670 616706 615720 616376 613954 676191 673866 681197 674378 674551 673247 676903 676019 684761 678990 679320 678476 679151 677438 680023 673648 674105 675005 674033 672254 677546 675359 675866 674403 675282 675322 682508 673850 675322 673656 676027 675489 684431 678609 677187 678271 677993 677128 675226 673787 674568 674421 675354 672226 678445 675577 675087 674813 637541 636710 642283 635539 634850 634655 637964 638982 643456 636863 637832 635435 638622 638005 641913 635066 633278 634212 638140 636020 635031 636545 636497 634414 636965 637058 641611 635347 634875 635434 637938 639620 643009 637255 637317 636255 638743 637327 641818 635525 633991 633721 637649 635794 634961 636961 637332 634873
average_num_operations_total=643355
stdev_num_operations_total=2094
sum_num_operations_total=92643215
min_num_operations_total=612670
max_num_operations_total=684761
first_time_thread_terminate_by_thread=10000233 10000160 10000170 10000365 10000166 10000162 10000169 10000160 10000167 10000164 10000168 10000162 10000161 10000422 10000168 10000163 10000170 10001347 10000169 10000165 10000170 10000167 10000167 10000162 10000167 10000160 10000169 10000162 10000161 10000160 10000169 10000160 10000167 10000163 10000160 10000167 10000169 10000160 10000164 10000163 10000170 10000165 10000170 10000164 10000170 10000167 10000164 10000170 10000168 10000167 10000169 10000169 10000169 10000168 10000167 10000165 10000168 10000168 10000168 10000168 10000170 10000165 10000168 10000166 10000168 10000159 10000166 10000170 10000168 10000168 10000164 10000167 10000168 10000167 10000169 10000162 10000169 10000168 10000167 10000165 10000166 10000168 10000164 10000168 10000169 10000167 10000176 10000166 10000165 10000165 10000168 10000170 10000168 10000169 10000164 10000167 10000161 10000167 10002542 10000163 10000163 10000168 10000164 10000165 10000167 10000165 10000165 10000164 10000169 10000169 10000161 10000166 10000161 10000164 10000173 10000165 10000165 10000163 10000163 10000170 10000161 10000166 10000163 10000166 10000163 10000165 10000164 10000161 10000165 10000169 10000170 10000168 10000166 10000167 10000164 10000161 10000161 10000168 10000161 10000162 10000163 10000163 10000163 10000160
min_time_thread_terminate_total=10000159
max_time_thread_terminate_total=10002542
first_time_thread_start_by_thread=9 5 5 5 4 5 5 5 5 5 4 5 5 4 4 4 4 4 5 5 5 5 5 4 4 5 5 5 4 5 5 5 5 5 4 5 5 4 4 4 4 4 5 5 5 5 5 4 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99993135069321
threads_final_size=10000151
final_keysum=99993135069321
final_size=10000151
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=46321196
total_deletes=46322019
total_updates=92643215
total_queries=0
total_ops=92643215
find_throughput=0
rq_throughput=0
update_throughput=9264321
query_throughput=0
total_throughput=9264321


total find                    : 0
total rq                      : 0
total inserts                 : 46321196
total deletes                 : 46322019
total updates                 : 92643215
total queries                 : 0
total ops                     : 92643215
find throughput               : 0
rq throughput                 : 0
update throughput             : 9264321
query throughput              : 0
total throughput              : 9264321

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=56.9969
PAPI_L3_TCM=31.7309
PAPI_TOT_CYC=43128.9
PAPI_TOT_INS=1286.86
garbage=0
total_execution_walltime=15.859s
REALTIME_MAIN_END_PERF_FORMAT=6987323.983658938
[time_cmd_output] time_elapsed_sec=16.06, faults_major=0, faults_minor=371057, mem_maxresident_kb=1438780, user_cputime=2203.95, sys_cputime=10.09, percent_cpu=13780%
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