__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000386.txt
__path_data=data/data000386.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211484.2710912
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-63,128-191,64-127,192-255
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.021s
prefilling_size=7707163 prefilling_elapsed_millis=1s
prefilling_size=7962113 prefilling_elapsed_millis=1.1s
prefilling_size=8186885 prefilling_elapsed_millis=1.201s
prefilling_size=8384306 prefilling_elapsed_millis=1.301s
prefilling_size=8558390 prefilling_elapsed_millis=1.401s
prefilling_size=8711078 prefilling_elapsed_millis=1.501s
prefilling_size=8848612 prefilling_elapsed_millis=1.601s
prefilling_size=8969597 prefilling_elapsed_millis=1.701s
prefilling_size=9077207 prefilling_elapsed_millis=1.801s
prefilling_size=9173243 prefilling_elapsed_millis=1.901s
prefilling_size=9259693 prefilling_elapsed_millis=2.002s
prefilling_size=9335341 prefilling_elapsed_millis=2.102s
prefilling_size=9404190 prefilling_elapsed_millis=2.202s
prefilling_size=9466125 prefilling_elapsed_millis=2.302s
prefilling_size=9521390 prefilling_elapsed_millis=2.402s
prefilling_size=9568853 prefilling_elapsed_millis=2.502s
prefilling_size=9613494 prefilling_elapsed_millis=2.602s
prefilling_size=9652087 prefilling_elapsed_millis=2.702s
prefilling_size=9687069 prefilling_elapsed_millis=2.803s
prefilling_size=9719247 prefilling_elapsed_millis=2.903s
prefilling_size=9746379 prefilling_elapsed_millis=3.003s
prefilling_size=9772152 prefilling_elapsed_millis=3.103s
prefilling_size=9794545 prefilling_elapsed_millis=3.203s
prefilling_size=9816458 prefilling_elapsed_millis=3.304s
finished prefilling to size 9816491 for expected size 10000000 keysum=98150307696999, performing 80588835 updates; total_prefilling_elapsed_ms=3404 ms)
pref_size=9816491
pref_millis=3404
prefill_elapsed_ms=3421
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=2091

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211487.461524289
REALTIME_END_PERF_FORMAT=211497.461626829

###############################################################################
################################## 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 (256 threads)...
partitioned into 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.172s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1022 2025 3942 7490 13792 24394 41718 68204 106483 158897 225438 305396 395300 488080 575694 651428 706877 738486 743849 723153 679940 620580 550166 474711 397362 323284 255340 196240 146466 106149 74405 51002 34026 22124 14228 8733 5049 2753 1479 786 362 173 67 30 16 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 2 19 108 394 1188 3190 7070 15232 29925 54069 92356 145480 215492 302520 400466 499960 595979 675268 733123 764545 766366 739300 690994 625621 552060 471440 391228 314440 246014 186783 137893 97808 67978 45928 30020 19723 12417 7345 4027 2172 1210 551 279 104 44 29 5 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2044 4050 7884 14980 27584 48788 83436 136408 212966 317794 450876 610792 790600 976160 1151388 1302856 1413754 1476972 1487698 1446306 1359880 1241160 1100332 949422 794724 646568 510680 392480 292932 212298 148810 102004 68052 44248 28456 17466 10098 5506 2958 1572 724 346 134 60 32 6 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 2 19 108 394 1188 3190 7070 15232 29925 54069 92356 145480 215492 302520 400466 499960 595979 675268 733123 764545 766366 739300 690994 625621 552060 471440 391228 314440 246014 186783 137893 97808 67978 45928 30020 19723 12417 7345 4027 2172 1210 551 279 104 44 29 5 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 1.99805 1.9907 1.97333 1.95003 1.92069 1.88435 1.85509 1.81744 1.78062 1.74611 1.70938 1.67734 1.64719 1.61735 1.58975 1.56578 1.54256 1.52236 1.50363 1.48609 1.47012 1.45635 1.44327 1.43143 1.41853 1.40679 1.39492 1.38427 1.37318 1.36237 1.35047 1.34273 1.33358 1.3251 1.32155 1.30689 1.28908 1.27263 1.26862 1.26572 1.23028 1.23895 1.19364 1.22388 1.26667 1.09375 1.16667 1

tree_stats_height=58
tree_stats_numInternals=9948166
tree_stats_numLeaves=9948167
tree_stats_numNodes=19896333
tree_stats_numKeys=9948167

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

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32768 65408 129600 252288 479360 882688 1561216 2669952 4365056 6814912 10169408 14428032 19545344 25299200 31237120 36844416 41691392 45240128 47263104 47606336 46281792 43516160 39717120 35210624 30381504 25431168 20690176 16341760 12559360 9373824 6793536 4761920 3264128 2177664 1415936 910592 558912 323136 176192 94656 50304 23168 11072 4288 1920 1024 192 64
tree_stats_sizeInBytes=636682656

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=8485


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:57 8:6102 16:216279 32:186299 64:340 128:8
    (2^01, 2^02]: 1
    (2^02, 2^03]: 57
    (2^03, 2^04]: 6102
    (2^04, 2^05]: 216279
    (2^05, 2^06]: 186299
    (2^06, 2^07]: 340
    (2^07, 2^08]: 8
sum_limbo_reclamation_event_size_total=12481170
count_limbo_reclamation_event_size_by_thread=6392 6392 6392 6390 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392 6392
count_limbo_reclamation_event_size_total=409086
sum_limbo_reclamation_event_count_total=409215
first_thread_announced_epoch_by_thread=16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970 16970
sum_num_inserts_by_thread=198575 200080 198276 197638 198615 198480 196831 196302 196214 196656 195028 195509 198248 197401 196617 196341 198171 197760 197272 197495 197808 197500 196277 195566 195678 196320 195439 196900 198219 197668 197556 196421 198561 199457 197660 197071 196754 197543 196270 196407 196504 198600 197850 197684 198475 198391 197588 197461 197487 196863 195961 195325 195549 196441 195198 198213 197338 197215 196765 196454 198448 198031 195944 196350
sum_num_inserts_total=12618719
sum_num_deletes_by_thread=198952 199802 199215 198405 198683 198231 196681 197609 196502 196095 195611 195310 198327 198078 196491 196391 198241 197694 197489 196984 197962 197487 196245 196185 195524 195413 195131 197695 197759 198215 197807 196396 199173 197896 197651 197006 197579 197311 196554 195842 196338 198729 197716 197915 198225 198094 198019 197656 196854 197148 194191 195440 195795 195663 194575 197403 197880 196996 196921 196745 197629 197765 196980 196361
sum_num_deletes_total=12618660
sum_num_searches_by_thread=3587000 3583721 3572167 3550807 3571715 3565280 3544136 3543867 3539915 3528204 3513156 3517055 3560573 3556139 3541572 3538910 3560831 3560848 3554710 3550854 3557145 3545889 3533313 3527725 3531024 3528361 3509540 3557900 3560133 3561520 3551570 3551254 3575549 3572379 3560825 3548811 3550028 3552109 3531846 3539391 3529701 3575449 3559274 3562679 3570315 3568962 3557875 3554443 3539917 3532241 3516046 3515328 3524100 3527016 3511520 3553951 3552179 3551152 3543797 3545033 3557375 3554292 3550631 3535170
sum_num_searches_total=227076218
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3984527 3983603 3969658 3946850 3969013 3961991 3937648 3937778 3932631 3920955 3903795 3907874 3957148 3951618 3934680 3931642 3957243 3956302 3949471 3945333 3952915 3940876 3925835 3919476 3922226 3920094 3900110 3952495 3956111 3957403 3946933 3944071 3973283 3969732 3956136 3942888 3944361 3946963 3924670 3931640 3922543 3972778 3954840 3958278 3967015 3965447 3953482 3949560 3934258 3926252 3906198 3906093 3915444 3919120 3901293 3949567 3947397 3945363 3937483 3938232 3953452 3950088 3943555 3927881
average_num_operations_total=3942399
stdev_num_operations_total=2535
sum_num_operations_total=252313597
min_num_operations_total=3900110
max_num_operations_total=3984527
first_time_thread_terminate_by_thread=10000104 10000104 10000104 10000104 10000104 10000104 10000104 10000104 10000107 10000107 10000107 10000107 10000105 10000105 10000105 10000105 10000105 10000105 10000105 10000105 10000107 10000107 10000107 10000107 10000105 10000105 10000105 10000105 10000106 10000106 10000106 10000106 10000105 10000105 10000105 10000105 10000106 10000106 10000106 10000106 10000108 10000108 10000108 10000108 10000107 10000107 10000107 10000106 10000106 10000106 10000106 10000106 10000104 10000104 10000104 10000104 10000107 10000107 10000107 10000107 10000104 10000104 10000104 10000104
min_time_thread_terminate_total=10000104
max_time_thread_terminate_total=10000108
first_time_thread_start_by_thread=7 7 7 7 8 8 8 8 7 7 8 8 8 8 8 8 8 8 8 8 6 6 6 6 7 7 7 7 7 7 7 7 9 9 9 9 10 10 10 10 11 10 11 10 10 10 10 10 7 7 7 7 10 10 10 10 9 9 9 9 9 9 9 9
min_time_thread_start_total=6
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=99487008656527
threads_final_size=9948167
final_keysum=99487008656527
final_size=9948167
validate_result=success
Validation OK.
Structural validation OK.

total_find=227076218
total_rq=0
total_inserts=12618719
total_deletes=12618660
total_updates=25237379
total_queries=227076218
total_ops=252313597
find_throughput=22707621
rq_throughput=0
update_throughput=2523737
query_throughput=22707621
total_throughput=25231359


total find                    : 227076218
total rq                      : 0
total inserts                 : 12618719
total deletes                 : 12618660
total updates                 : 25237379
total queries                 : 227076218
total ops                     : 252313597
find throughput               : 22707621
rq throughput                 : 0
update throughput             : 2523737
query throughput              : 22707621
total throughput              : 25231359

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=491702563149404
total_execution_walltime=13.697s
REALTIME_MAIN_END_PERF_FORMAT=211497.700885507
[time_cmd_output] time_elapsed_sec=13.84, faults_major=0, faults_minor=235316, mem_maxresident_kb=942404, user_cputime=878.47, sys_cputime=4.95, percent_cpu=6379%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2