__step=000702
__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=data000702.txt
__path_data=data/data000702.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987324.240712784
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.015s
prefilling_size=3598175 prefilling_elapsed_millis=1.001s
prefilling_size=3875391 prefilling_elapsed_millis=1.101s
prefilling_size=4143571 prefilling_elapsed_millis=1.201s
prefilling_size=4398480 prefilling_elapsed_millis=1.302s
prefilling_size=4642376 prefilling_elapsed_millis=1.402s
prefilling_size=4876127 prefilling_elapsed_millis=1.502s
prefilling_size=5099543 prefilling_elapsed_millis=1.602s
prefilling_size=5312837 prefilling_elapsed_millis=1.702s
prefilling_size=5516847 prefilling_elapsed_millis=1.803s
prefilling_size=5710460 prefilling_elapsed_millis=1.903s
prefilling_size=5899334 prefilling_elapsed_millis=2.003s
prefilling_size=6078631 prefilling_elapsed_millis=2.103s
prefilling_size=6250132 prefilling_elapsed_millis=2.203s
prefilling_size=6411422 prefilling_elapsed_millis=2.304s
prefilling_size=6567215 prefilling_elapsed_millis=2.404s
prefilling_size=6716267 prefilling_elapsed_millis=2.504s
prefilling_size=6858928 prefilling_elapsed_millis=2.604s
prefilling_size=6995215 prefilling_elapsed_millis=2.704s
prefilling_size=7125624 prefilling_elapsed_millis=2.805s
prefilling_size=7248957 prefilling_elapsed_millis=2.905s
prefilling_size=7368667 prefilling_elapsed_millis=3.005s
prefilling_size=7481188 prefilling_elapsed_millis=3.105s
prefilling_size=7590662 prefilling_elapsed_millis=3.205s
prefilling_size=7694584 prefilling_elapsed_millis=3.306s
prefilling_size=7795275 prefilling_elapsed_millis=3.406s
prefilling_size=7890666 prefilling_elapsed_millis=3.506s
prefilling_size=7981714 prefilling_elapsed_millis=3.606s
prefilling_size=8069250 prefilling_elapsed_millis=3.706s
prefilling_size=8152190 prefilling_elapsed_millis=3.807s
prefilling_size=8232509 prefilling_elapsed_millis=3.907s
prefilling_size=8308599 prefilling_elapsed_millis=4.007s
prefilling_size=8382431 prefilling_elapsed_millis=4.107s
prefilling_size=8451841 prefilling_elapsed_millis=4.207s
prefilling_size=8519247 prefilling_elapsed_millis=4.308s
prefilling_size=8583782 prefilling_elapsed_millis=4.408s
prefilling_size=8644979 prefilling_elapsed_millis=4.508s
prefilling_size=8702787 prefilling_elapsed_millis=4.608s
prefilling_size=8757741 prefilling_elapsed_millis=4.709s
prefilling_size=8810794 prefilling_elapsed_millis=4.809s
prefilling_size=8862321 prefilling_elapsed_millis=4.909s
prefilling_size=8910894 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1089080 keys with 144 threads...
tid=0 thread_prefill_amount=58296 percent_done_estimate=770.8 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=59684 percent_done_estimate=789.2 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=61072 percent_done_estimate=807.5 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=62460 percent_done_estimate=825.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=63848 percent_done_estimate=844.2 elapsed_s=5 est_remaining_s=-10 / -5
timing_elapsed 0.13278s
prefilling_size=10000000 prefilling_elapsed_millis=0.133s
finished prefilling to size 10000000 for expected size 10000000 keysum=100016366253886, performing 46687414 updates; total_prefilling_elapsed_ms=133 ms)
pref_size=10000000
pref_millis=133
prefill_elapsed_ms=133
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987329.714844393
REALTIME_END_PERF_FORMAT=6987339.715013279

###############################################################################
################################## 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=9995919
tree_stats_avgDegreeAtDepth=9.99592e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99592e+06
tree_stats_avgDegree=9.99592e+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=309899 310681 310664 310278 310910 311395 311155 310835 310915 310990 311164 311282 310864 310559 311405 309711 310712 312081 312084 311361 309673 311147 310930 306884 309937 310832 311169 310736 311478 310971 312276 311517 311707 310692 310166 312019 310765 311378 310919 310163 311021 313123 311188 310548 310022 311196 311074 306250 312640 313275 312721 311975 312753 313951 313467 313691 315046 313932 313824 318253 313583 314462 312760 312530 312402 315149 312099 310994 313449 313207 312710 310231 313130 312507 313444 312164 313284 313676 312560 313763 314192 312435 314303 318028 314015 313891 311247 312239 313150 314607 311444 312345 312899 313901 314016 310099 301082 300927 301077 301334 300893 302152 300906 301883 302100 302831 302200 303745 302642 302007 301358 300474 301136 301890 301729 301548 302887 302271 302270 299412 301608 300458 301845 301403 301003 301733 302062 301873 302157 301871 301906 301805 302977 301713 301352 300325 302060 301596 301375 302466 302744 302424 302320 299039
sum_num_inserts_total=44436038
sum_num_deletes_by_thread=309335 310391 310783 310318 311150 311661 311131 311708 310921 310515 311120 310805 310955 310589 311801 311150 311229 312333 311260 310617 312302 310786 310780 305846 310174 311399 310484 310491 312368 311475 310848 310761 311376 310880 310334 311320 310657 310934 312205 310080 311784 312976 311140 311121 310859 311143 309640 306822 312891 312581 313647 312501 312845 313690 313669 312988 313904 313637 314723 317908 314629 313983 313715 313274 312388 313901 312070 311713 312910 312292 313143 310367 313152 312378 313368 311968 312672 313196 313823 312468 313885 314055 313601 316943 313069 313577 312705 311039 312134 314889 311413 311860 313360 312523 312732 310934 302203 301558 302128 301576 301752 301041 302873 302102 301793 301330 302406 302950 303605 302572 301522 301814 302386 301557 301694 301204 302056 302294 301793 298488 301845 301075 301677 301246 301317 300643 301720 302813 301578 301758 302541 302408 302405 302672 301742 300654 301437 301899 301560 301639 302725 302634 301685 300280
sum_num_deletes_total=44438520
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=619234 621072 621447 620596 622060 623056 622286 622543 621836 621505 622284 622087 621819 621148 623206 620861 621941 624414 623344 621978 621975 621933 621710 612730 620111 622231 621653 621227 623846 622446 623124 622278 623083 621572 620500 623339 621422 622312 623124 620243 622805 626099 622328 621669 620881 622339 620714 613072 625531 625856 626368 624476 625598 627641 627136 626679 628950 627569 628547 636161 628212 628445 626475 625804 624790 629050 624169 622707 626359 625499 625853 620598 626282 624885 626812 624132 625956 626872 626383 626231 628077 626490 627904 634971 627084 627468 623952 623278 625284 629496 622857 624205 626259 626424 626748 621033 603285 602485 603205 602910 602645 603193 603779 603985 603893 604161 604606 606695 606247 604579 602880 602288 603522 603447 603423 602752 604943 604565 604063 597900 603453 601533 603522 602649 602320 602376 603782 604686 603735 603629 604447 604213 605382 604385 603094 600979 603497 603495 602935 604105 605469 605058 604005 599319
average_num_operations_total=617184
stdev_num_operations_total=848
sum_num_operations_total=88874558
min_num_operations_total=597900
max_num_operations_total=636161
first_time_thread_terminate_by_thread=10000250 10000176 10000174 10000172 10000176 10000175 10000174 10000176 10000170 10000176 10000168 10000176 10000173 10000172 10000175 10000176 10000174 10000168 10000169 10000174 10000176 10000175 10000172 10000175 10000172 10000176 10000175 10000172 10000175 10000175 10000170 10000175 10000170 10000176 10000168 10000176 10000174 10000168 10000175 10000176 10000174 10000177 10000168 10000172 10000176 10000175 10000168 10000175 10000172 10000185 10000171 10000174 10000167 10000172 10000168 10000171 10000171 10000170 10000173 10000173 10000172 10000169 10000171 10000174 10000173 10000173 10000167 10000170 10000169 10000174 10000166 10000169 10000172 10000170 10000168 10000174 10000167 10000167 10000168 10000171 10000171 10000168 10000174 10000173 10000171 10000169 10000171 10000172 10000173 10000172 10000168 10000170 10000173 10000173 10000166 10000169 10000175 10000174 10000170 10000175 10000174 10000174 10000173 10000168 10000169 10000167 10000175 10000174 10000171 10000173 10000175 10000171 10000170 10000167 10000173 10000175 10000172 10000168 10000169 10000175 10000175 10000174 10000170 10000175 10000174 10000174 10000173 10000168 10000169 10000175 10000175 10000175 10000168 10000167 10000175 10000170 10000170 10000172 10000174 10000175 10000172 10000168 10000169 10000168
min_time_thread_terminate_total=10000166
max_time_thread_terminate_total=10000250
first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 6 5 5 5 5 5 6 6 5 5 6 6 5 5 5 6 6 6 6 5 6 5 6 6 6 5 5 6 5 6 6 6 5 5 6 6 5 5 5 6 6 6 6 5 6 5 6 6 6 5 5 6 5 5 6 5 6 5 5 6 6 5 5 5 6 6 6 5 5 5 5 5 5 6 6 6 6 6 6 5 6 5 5 6 6 5 5 5 6 6 6 5 5 5 5 5 5 6 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99976180303055
threads_final_size=9995919
final_keysum=99976180303055
final_size=9995919
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=44436038
total_deletes=44438520
total_updates=88874558
total_queries=0
total_ops=88874558
find_throughput=0
rq_throughput=0
update_throughput=8887455
query_throughput=0
total_throughput=8887455


total find                    : 0
total rq                      : 0
total inserts                 : 44436038
total deletes                 : 44438520
total updates                 : 88874558
total queries                 : 0
total ops                     : 88874558
find throughput               : 0
rq throughput                 : 0
update throughput             : 8887455
query throughput              : 0
total throughput              : 8887455

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=55.1544
PAPI_L3_TCM=30.6724
PAPI_TOT_CYC=44944.4
PAPI_TOT_INS=1293.81
garbage=0
total_execution_walltime=15.793s
REALTIME_MAIN_END_PERF_FORMAT=6987340.34794728
[time_cmd_output] time_elapsed_sec=15.99, faults_major=0, faults_minor=375381, mem_maxresident_kb=1453964, user_cputime=2192.62, sys_cputime=9.93, percent_cpu=13771%
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