__step=000699
__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 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=data000699.txt
__path_data=data/data000699.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987276.140503769
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.036s
prefilling_size=3968221 prefilling_elapsed_millis=1.001s
prefilling_size=4265405 prefilling_elapsed_millis=1.101s
prefilling_size=4547417 prefilling_elapsed_millis=1.201s
prefilling_size=4814870 prefilling_elapsed_millis=1.302s
prefilling_size=5069624 prefilling_elapsed_millis=1.402s
prefilling_size=5310257 prefilling_elapsed_millis=1.502s
prefilling_size=5539592 prefilling_elapsed_millis=1.603s
prefilling_size=5756523 prefilling_elapsed_millis=1.703s
prefilling_size=5961308 prefilling_elapsed_millis=1.803s
prefilling_size=6157173 prefilling_elapsed_millis=1.904s
prefilling_size=6343886 prefilling_elapsed_millis=2.004s
prefilling_size=6519923 prefilling_elapsed_millis=2.104s
prefilling_size=6688600 prefilling_elapsed_millis=2.205s
prefilling_size=6848468 prefilling_elapsed_millis=2.305s
prefilling_size=7000648 prefilling_elapsed_millis=2.405s
prefilling_size=7145073 prefilling_elapsed_millis=2.506s
prefilling_size=7282712 prefilling_elapsed_millis=2.606s
prefilling_size=7413316 prefilling_elapsed_millis=2.707s
prefilling_size=7537030 prefilling_elapsed_millis=2.807s
prefilling_size=7656243 prefilling_elapsed_millis=2.907s
prefilling_size=7768671 prefilling_elapsed_millis=3.008s
prefilling_size=7875184 prefilling_elapsed_millis=3.108s
prefilling_size=7977377 prefilling_elapsed_millis=3.208s
prefilling_size=8073848 prefilling_elapsed_millis=3.309s
prefilling_size=8164929 prefilling_elapsed_millis=3.409s
prefilling_size=8252491 prefilling_elapsed_millis=3.509s
prefilling_size=8335464 prefilling_elapsed_millis=3.61s
prefilling_size=8415032 prefilling_elapsed_millis=3.71s
prefilling_size=8490247 prefilling_elapsed_millis=3.81s
prefilling_size=8560633 prefilling_elapsed_millis=3.911s
prefilling_size=8628676 prefilling_elapsed_millis=4.011s
prefilling_size=8693533 prefilling_elapsed_millis=4.111s
prefilling_size=8756197 prefilling_elapsed_millis=4.212s
prefilling_size=8814022 prefilling_elapsed_millis=4.312s
prefilling_size=8869271 prefilling_elapsed_millis=4.412s
prefilling_size=8924073 prefilling_elapsed_millis=4.513s
prefilling_size=8975481 prefilling_elapsed_millis=4.613s
prefilling_size=9024704 prefilling_elapsed_millis=4.713s
prefilling_size=9070306 prefilling_elapsed_millis=4.814s
prefilling_size=9113711 prefilling_elapsed_millis=4.914s
prefilling_size=9155922 prefilling_elapsed_millis=5.014s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 843914 keys with 96 threads...
tid=0 thread_prefill_amount=87486 percent_done_estimate=995.2 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=89569 percent_done_estimate=1018.9 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=91652 percent_done_estimate=1042.6 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=93735 percent_done_estimate=1066.3 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.0923194s
prefilling_size=10000000 prefilling_elapsed_millis=0.092s
finished prefilling to size 10000000 for expected size 10000000 keysum=100013003496824, performing 52403088 updates; total_prefilling_elapsed_ms=92 ms)
pref_size=10000000
pref_millis=92
prefill_elapsed_ms=92
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987281.568159593
REALTIME_END_PERF_FORMAT=6987291.568259556

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9994510
tree_stats_avgDegreeAtDepth=9.99451e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99451e+06
tree_stats_avgDegree=9.99451e+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=536502 539325 540775 539059 539552 539617 540919 539463 542504 543001 536942 539534 541918 538961 539495 538728 543913 541308 539401 538524 539960 540209 539638 538686 539340 538861 540503 537767 540814 538166 539751 540363 542840 542701 537148 541720 541928 538931 539798 538626 542171 541103 538925 538756 540903 540896 540158 538716 516762 515303 516631 515349 515343 516700 516656 517158 520238 520802 518920 519104 519818 519804 514332 514490 514702 515892 513906 511675 515819 514924 516544 515235 516097 514514 516590 514812 515647 516099 515630 517877 520645 519557 518019 519499 519462 518131 516129 514535 514191 514740 513982 511557 517968 515621 515770 515795
sum_num_inserts_total=50707793
sum_num_deletes_by_thread=538552 539112 539851 538833 542236 538771 540808 539689 542959 542056 537996 540619 541820 539137 540499 538156 542789 541030 540075 540622 540379 539697 540030 538467 538937 540281 539782 538877 541274 539298 541647 539725 542064 542744 537647 540124 542174 538932 540154 538110 543282 541523 540957 539999 540493 540916 539290 537494 515451 515535 518138 515442 516736 517488 517064 517646 521180 518375 519959 520346 520519 519711 516552 512991 516479 516671 513510 512771 517227 515558 516806 515854 516468 515360 517771 515924 517753 516839 515171 517146 520106 518218 519615 520727 519918 520353 514506 514461 515209 515617 513364 512649 516131 514942 515578 516099
sum_num_deletes_total=50737841
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1075054 1078437 1080626 1077892 1081788 1078388 1081727 1079152 1085463 1085057 1074938 1080153 1083738 1078098 1079994 1076884 1086702 1082338 1079476 1079146 1080339 1079906 1079668 1077153 1078277 1079142 1080285 1076644 1082088 1077464 1081398 1080088 1084904 1085445 1074795 1081844 1084102 1077863 1079952 1076736 1085453 1082626 1079882 1078755 1081396 1081812 1079448 1076210 1032213 1030838 1034769 1030791 1032079 1034188 1033720 1034804 1041418 1039177 1038879 1039450 1040337 1039515 1030884 1027481 1031181 1032563 1027416 1024446 1033046 1030482 1033350 1031089 1032565 1029874 1034361 1030736 1033400 1032938 1030801 1035023 1040751 1037775 1037634 1040226 1039380 1038484 1030635 1028996 1029400 1030357 1027346 1024206 1034099 1030563 1031348 1031894
average_num_operations_total=1056725
stdev_num_operations_total=2448
sum_num_operations_total=101445634
min_num_operations_total=1024206
max_num_operations_total=1086702
first_time_thread_terminate_by_thread=10000165 10000104 10000102 10000099 10000104 10000099 10000100 10000100 10000105 10000104 10000099 10000104 10000102 10000100 10000102 10000104 10000104 10000104 10000101 10000104 10000100 10000100 10000102 10001380 10000103 10000378 10000536 10000104 10000104 10000099 10000102 10000100 10000104 10000103 10000099 10000104 10000102 10000100 10000104 10000104 10000102 10000104 10000101 10000104 10000104 10000100 10000099 10000103 10000103 10000103 10000101 10000101 10000099 10000103 10000099 10000105 10000103 10001870 10000101 10000103 10000100 10000100 10000102 10000105 10000100 10000099 10000103 10000101 10000101 10000100 10000100 10000103 10000101 10000102 10000101 10000101 10000099 10000103 10000099 10000105 10000103 10000101 10000103 10000103 10000100 10000101 10000103 10000102 10000100 10000100 10000103 10000099 10000102 10000102 10000100 10000103
min_time_thread_terminate_total=10000099
max_time_thread_terminate_total=10001870
first_time_thread_start_by_thread=9 6 6 5 5 6 6 5 6 5 5 5 5 6 6 5 5 6 5 5 5 5 5 5 4 6 6 5 5 6 6 5 6 5 5 5 5 6 6 5 5 6 5 5 5 5 5 5 6 6 5 6 6 6 6 6 6 5 5 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 5 6 6 6 6 6 6 5 5 6 6 6 6 6 6 6 6 5 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99956675482421
threads_final_size=9994510
final_keysum=99956675482421
final_size=9994510
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=50707793
total_deletes=50737841
total_updates=101445634
total_queries=0
total_ops=101445634
find_throughput=0
rq_throughput=0
update_throughput=10144563
query_throughput=0
total_throughput=10144563


total find                    : 0
total rq                      : 0
total inserts                 : 50707793
total deletes                 : 50737841
total updates                 : 101445634
total queries                 : 0
total ops                     : 101445634
find throughput               : 0
rq throughput                 : 0
update throughput             : 10144563
query throughput              : 0
total throughput              : 10144563

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=56.0698
PAPI_L3_TCM=30.052
PAPI_TOT_CYC=26311.6
PAPI_TOT_INS=1282.16
garbage=0
total_execution_walltime=15.74s
REALTIME_MAIN_END_PERF_FORMAT=6987291.881321880
[time_cmd_output] time_elapsed_sec=15.94, faults_major=0, faults_minor=331918, mem_maxresident_kb=1295096, user_cputime=1464.04, sys_cputime=6.32, percent_cpu=9219%
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