__step=000386
__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" ./natarajan_ext_bst_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000386.txt
__path_data=data/data000386.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983483.349544457
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=natarajan_ext_bst_lf
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=5
DEL_FRAC=5
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=5.0 5.0
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=32
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.02s
prefilling_size=7198616 prefilling_elapsed_millis=1.001s
prefilling_size=7471526 prefilling_elapsed_millis=1.101s
prefilling_size=7716242 prefilling_elapsed_millis=1.201s
prefilling_size=7933677 prefilling_elapsed_millis=1.301s
prefilling_size=8129527 prefilling_elapsed_millis=1.402s
prefilling_size=8304103 prefilling_elapsed_millis=1.502s
prefilling_size=8461958 prefilling_elapsed_millis=1.602s
prefilling_size=8604838 prefilling_elapsed_millis=1.702s
prefilling_size=8732439 prefilling_elapsed_millis=1.802s
prefilling_size=8849074 prefilling_elapsed_millis=1.903s
prefilling_size=8953447 prefilling_elapsed_millis=2.003s
prefilling_size=9047484 prefilling_elapsed_millis=2.103s
prefilling_size=9132947 prefilling_elapsed_millis=2.203s
prefilling_size=9210741 prefilling_elapsed_millis=2.303s
prefilling_size=9280084 prefilling_elapsed_millis=2.404s
prefilling_size=9345244 prefilling_elapsed_millis=2.504s
prefilling_size=9403380 prefilling_elapsed_millis=2.604s
prefilling_size=9455858 prefilling_elapsed_millis=2.704s
prefilling_size=9503202 prefilling_elapsed_millis=2.804s
prefilling_size=9546975 prefilling_elapsed_millis=2.904s
prefilling_size=9585943 prefilling_elapsed_millis=3.005s
prefilling_size=9623080 prefilling_elapsed_millis=3.105s
prefilling_size=9657371 prefilling_elapsed_millis=3.205s
prefilling_size=9687054 prefilling_elapsed_millis=3.305s
prefilling_size=9714278 prefilling_elapsed_millis=3.405s
prefilling_size=9738221 prefilling_elapsed_millis=3.506s
prefilling_size=9761790 prefilling_elapsed_millis=3.606s
prefilling_size=9782171 prefilling_elapsed_millis=3.706s
prefilling_size=9801059 prefilling_elapsed_millis=3.806s
finished prefilling to size 9801070 for expected size 10000000 keysum=98013115912374, performing 78655736 updates; total_prefilling_elapsed_ms=3907 ms)
pref_size=9801070
pref_millis=3907
prefill_elapsed_ms=3928
6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=3594

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983487.454812830
REALTIME_END_PERF_FORMAT=6983497.454960908

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (192 threads)...
partitioned into 1020 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.298s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 510 999 1950 3735 6973 12564 21834 36425 57896 88264 128324 179055 240593 310302 385105 459513 527793 584994 628788 656698 668141 662827 641831 604730 555786 497826 434659 369004 303479 240974 185371 138028 99823 69730 46887 30798 19753 12006 7092 4005 2118 1103 464 192 59 31 11 5 6 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 21 48 165 497 1382 3294 7243 14954 27528 48204 77593 117517 170884 235499 310697 391233 470592 541200 600878 645255 673455 683823 678932 653674 613746 560993 500314 434529 365984 296577 232714 176233 129916 92573 62976 41843 27500 16920 10179 5892 3133 1742 736 325 87 51 17 4 9 6
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1020 1998 3900 7470 13946 25128 43668 72850 115792 176528 256648 358110 481186 620604 770210 919026 1055586 1169988 1257576 1313396 1336282 1325654 1283662 1209460 1111572 995652 869318 738008 606958 481948 370742 276056 199646 139460 93774 61596 39506 24012 14184 8010 4236 2206 928 384 118 62 22 10 12 6
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 2 21 48 165 497 1382 3294 7243 14954 27528 48204 77593 117517 170884 235499 310697 391233 470592 541200 600878 645255 673455 683823 678932 653674 613746 560993 500314 434529 365984 296577 232714 176233 129916 92573 62976 41843 27500 16920 10179 5892 3133 1742 736 325 87 51 17 4 9 6
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99609 1.97941 1.97598 1.95769 1.93347 1.9009 1.86891 1.83413 1.79473 1.76226 1.72693 1.69767 1.67184 1.64487 1.62053 1.59661 1.5743 1.55419 1.53743 1.52219 1.50871 1.49602 1.48416 1.4711 1.45953 1.44786 1.43656 1.42448 1.41121 1.39702 1.38463 1.3723 1.3616 1.34927 1.3362 1.32843 1.32069 1.3039 1.29535 1.28236 1.26442 1.26039 1.21034 1.2069 1.15365 1.26271 1.17742 1.22727 1.6 1.25 1

tree_stats_height=60
tree_stats_numInternals=9929568
tree_stats_numLeaves=9929569
tree_stats_numNodes=19859137
tree_stats_numKeys=9929569

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=31.5436

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32640 63936 124800 239040 446272 804096 1397376 2331200 3705344 5648896 8212736 11459520 15397952 19859328 24646720 29408832 33778752 37439616 40242432 42028672 42761024 42420928 41077184 38702720 35570304 31860864 27818176 23616256 19422656 15422336 11863744 8833792 6388672 4462720 3000768 1971072 1264192 768384 453888 256320 135552 70592 29696 12288 3776 1984 704 320 384 192
tree_stats_sizeInBytes=635492384

6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=13077


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:57 4:1550 8:52354 16:351005 32:50118
    (2^01, 2^02]: 57
    (2^02, 2^03]: 1550
    (2^03, 2^04]: 52354
    (2^04, 2^05]: 351005
    (2^05, 2^06]: 50118
sum_limbo_reclamation_event_size_total=10339810
count_limbo_reclamation_event_size_by_thread=9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9480 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9480 9479 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481 9481
count_limbo_reclamation_event_size_total=455084
sum_limbo_reclamation_event_count_total=455184
first_thread_announced_epoch_by_thread=26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154 26154
sum_num_inserts_by_thread=216963 216715 219099 218630 218054 215538 217184 218316 219854 220334 218372 216652 218135 218509 219420 219880 219058 218136 216253 217688 218771 219674 217483 216520 216404 217616 218043 219160 218970 216433 216844 218209 218973 220214 217675 217789 217175 219047 219842 219865 218258 217041 215804 218113 219531 219949 217743 215904
sum_num_inserts_total=10469840
sum_num_deletes_by_thread=216774 216533 220035 218993 218153 216556 217191 217593 220216 220334 218702 216904 218061 219365 219676 220458 219293 217872 215959 216576 218955 220352 217064 216280 216551 216863 218648 219076 218106 216106 216090 218130 219742 219796 218379 216659 217763 218364 219991 220019 218825 217818 215981 217613 219127 219124 218116 215741
sum_num_deletes_total=10470523
sum_num_searches_by_thread=3892581 3916300 3949035 3946887 3929724 3893733 3906847 3924143 3966496 3969579 3926274 3909782 3916027 3934269 3954500 3964110 3943913 3921555 3893316 3913570 3937625 3954082 3914671 3890220 3893691 3912375 3944952 3943407 3932599 3890907 3907154 3927173 3964644 3968671 3927295 3909533 3914542 3930780 3959051 3960944 3944254 3917751 3888030 3913569 3938924 3958341 3917086 3894617
sum_num_searches_total=188529529
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4326318 4349548 4388169 4384510 4365931 4325827 4341222 4360052 4406566 4410247 4363348 4343338 4352223 4372143 4393596 4404448 4382264 4357563 4325528 4347834 4375351 4394108 4349218 4323020 4326646 4346854 4381643 4381643 4369675 4323446 4340088 4363512 4403359 4408681 4363349 4343981 4349480 4368191 4398884 4400828 4381337 4352610 4319815 4349295 4377582 4397414 4352945 4326262
average_num_operations_total=4363956
stdev_num_operations_total=3895
sum_num_operations_total=209469892
min_num_operations_total=4319815
max_num_operations_total=4410247
first_time_thread_terminate_by_thread=10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000149 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000147 10000148 10000147 10000147 10000147 10000149 10000147 10000147 10000147 10000147 10000148 10000148
min_time_thread_terminate_total=10000147
max_time_thread_terminate_total=10000149
first_time_thread_start_by_thread=5 4 4 4 4 4 4 5 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 5 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=5
sum_duration_all_ops_total=

threads_final_keysum=99296963022500
threads_final_size=9929569
final_keysum=99296963022500
final_size=9929569
validate_result=success
Validation OK.
Structural validation OK.

total_find=188529529
total_rq=0
total_inserts=10469840
total_deletes=10470523
total_updates=20940363
total_queries=188529529
total_ops=209469892
find_throughput=18852952
rq_throughput=0
update_throughput=2094036
query_throughput=18852952
total_throughput=20946989


total find                    : 188529529
total rq                      : 0
total inserts                 : 10469840
total deletes                 : 10470523
total updates                 : 20940363
total queries                 : 188529529
total ops                     : 209469892
find throughput               : 18852952
rq throughput                 : 0
update throughput             : 2094036
query throughput              : 18852952
total throughput              : 20946989

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=25.2528
PAPI_L3_TCM=9.16197
PAPI_TOT_CYC=6389.39
PAPI_TOT_INS=422.992
garbage=930837514880268
total_execution_walltime=14.483s
REALTIME_MAIN_END_PERF_FORMAT=6983497.844107355
[time_cmd_output] time_elapsed_sec=14.67, faults_major=0, faults_minor=234512, mem_maxresident_kb=940220, user_cputime=700.46, sys_cputime=2.88, percent_cpu=4791%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2