__step=000050
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000050.txt
__path_data=data/data000050.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207423.952542293
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=0.5
DEL_FRAC=0.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=0.5 0.5
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.013s
prefilling_size=7690052 prefilling_elapsed_millis=1s
prefilling_size=7937855 prefilling_elapsed_millis=1.1s
prefilling_size=8159348 prefilling_elapsed_millis=1.201s
prefilling_size=8354893 prefilling_elapsed_millis=1.301s
prefilling_size=8526050 prefilling_elapsed_millis=1.401s
prefilling_size=8676264 prefilling_elapsed_millis=1.501s
prefilling_size=8810426 prefilling_elapsed_millis=1.601s
prefilling_size=8933031 prefilling_elapsed_millis=1.701s
prefilling_size=9041676 prefilling_elapsed_millis=1.801s
prefilling_size=9137678 prefilling_elapsed_millis=1.901s
prefilling_size=9224987 prefilling_elapsed_millis=2.002s
prefilling_size=9300997 prefilling_elapsed_millis=2.102s
prefilling_size=9370284 prefilling_elapsed_millis=2.202s
prefilling_size=9432006 prefilling_elapsed_millis=2.302s
prefilling_size=9487019 prefilling_elapsed_millis=2.402s
prefilling_size=9537324 prefilling_elapsed_millis=2.502s
prefilling_size=9582611 prefilling_elapsed_millis=2.602s
prefilling_size=9623247 prefilling_elapsed_millis=2.702s
prefilling_size=9659133 prefilling_elapsed_millis=2.802s
prefilling_size=9692347 prefilling_elapsed_millis=2.903s
prefilling_size=9723135 prefilling_elapsed_millis=3.003s
prefilling_size=9751059 prefilling_elapsed_millis=3.103s
prefilling_size=9774690 prefilling_elapsed_millis=3.203s
prefilling_size=9795107 prefilling_elapsed_millis=3.304s
prefilling_size=9814806 prefilling_elapsed_millis=3.404s
finished prefilling to size 9814810 for expected size 10000000 keysum=98144519217358, performing 79808449 updates; total_prefilling_elapsed_ms=3504 ms)
pref_size=9814810
pref_millis=3504
prefill_elapsed_ms=3526
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=2116

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207427.506362624
REALTIME_END_PERF_FORMAT=207437.506423961

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

tree_stats_computeWalltime=0.233s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 502 981 1866 3517 6482 11691 20640 35268 58353 92306 139416 201481 277288 364307 455002 544464 623657 684031 724815 742294 733805 701121 647505 578661 501304 420301 340531 267435 202819 148569 105712 72680 48606 31482 19577 11972 7147 4036 2207 1254 685 369 180 77 43 17 6 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 8 23 96 215 552 1273 2742 6012 12183 24400 45196 77351 125674 190269 273612 365540 465271 563283 643247 707336 750783 766489 754737 716349 656018 582307 500071 413627 332051 257069 191426 138744 96754 65730 43387 27182 16797 10258 5865 3160 1823 1001 558 283 111 69 28 10 3 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1004 1962 3732 7034 12964 23382 41280 70536 116706 184612 278832 402962 554576 728614 910004 1088928 1247314 1368062 1449630 1484588 1467610 1402242 1295010 1157322 1002608 840602 681062 534870 405638 297138 211424 145360 97212 62964 39154 23944 14294 8072 4414 2508 1370 738 360 154 86 34 12 4 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 8 23 96 215 552 1273 2742 6012 12183 24400 45196 77351 125674 190269 273612 365540 465271 563283 643247 707336 750783 766489 754737 716349 656018 582307 500071 413627 332051 257069 191426 138744 96754 65730 43387 27182 16797 10258 5865 3160 1823 1001 558 283 111 69 28 10 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.98431 1.97709 1.95107 1.94239 1.92152 1.9018 1.88273 1.85436 1.82728 1.79093 1.75518 1.72259 1.68812 1.65691 1.62448 1.59831 1.57273 1.5484 1.52981 1.51206 1.49428 1.47773 1.46176 1.44684 1.43316 1.41921 1.4051 1.39267 1.37919 1.36626 1.35577 1.34376 1.33438 1.32385 1.31092 1.30577 1.29849 1.28236 1.27341 1.2841 1.27313 1.26934 1.2439 1.21389 1.27922 1.19767 1.17647 1.16667 1.25 1

tree_stats_height=59
tree_stats_numInternals=9836975
tree_stats_numLeaves=9836976
tree_stats_numNodes=19673951
tree_stats_numKeys=9836976

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

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16320 32128 62784 119424 225088 414848 748224 1320960 2257152 3734592 5907584 8922624 12894784 17746432 23315648 29120128 34845696 39914048 43777984 46388160 47506816 46963520 44871744 41440320 37034304 32083456 26899264 21793984 17115840 12980416 9508416 6765568 4651520 3110784 2014848 1252928 766208 457408 258304 141248 80256 43840 23616 11520 4928 2752 1088 384 128 64
tree_stats_sizeInBytes=629566432

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:134302 4:154324 8:27255 16:136
    (2^01, 2^02]: 134302
    (2^02, 2^03]: 154324
    (2^03, 2^04]: 27255
    (2^04, 2^05]: 136
sum_limbo_reclamation_event_size_total=1229680
count_limbo_reclamation_event_size_by_thread=4959 4961 4954 4908 4946 5021 5001 4940 4967 4963 4961 4933 4858 4880 4834 4844 5000 4940 4958 4913 4963 4989 4955 4927 4960 4962 4936 4934 4929 4903 4888 4862 4937 4975 4927 4927 4997 4984 5000 4910 4935 4932 4942 4940 4925 4897 4967 4981 4989 4940 4950 4927 4932 4932 4835 4945 4947 4842 4950 4936 4923 4978 4905 4961
count_limbo_reclamation_event_size_total=316017
sum_limbo_reclamation_event_count_total=405824
first_thread_announced_epoch_by_thread=16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914 16914
sum_num_inserts_by_thread=19783 19757 19281 19628 19519 19763 19685 19652 19538 19601 19580 19420 19464 19443 19556 19338 19579 19614 19184 19595 19763 19758 19564 19732 19510 19279 19471 19454 19407 19575 19264 19382 19833 19763 19757 19719 19757 19656 19495 19582 19637 19522 19626 19521 19655 19752 19996 19910 19629 19700 19301 19390 19675 19661 19347 19263 19350 19494 19422 19345 19670 19402 19545 19606
sum_num_inserts_total=1252120
sum_num_deletes_by_thread=19901 19850 19728 19487 19499 19858 19668 19557 19721 19857 19633 19560 19298 19255 19316 19389 19607 19597 19755 19484 19761 19560 19600 19645 19636 19595 19634 19505 19609 19448 19380 19447 19538 19875 19603 19747 19589 19500 20047 19452 19376 19402 19481 19434 19554 19319 19703 19451 19579 19613 19523 19739 19428 19498 19118 19481 19638 19353 19459 19319 19658 19960 19517 19580
sum_num_deletes_total=1252374
sum_num_searches_by_thread=3906668 3907975 3888878 3889650 3907648 3904634 3898623 3893353 3893798 3886663 3866093 3853375 3856903 3855773 3841867 3841285 3857560 3857202 3894366 3888510 3894478 3891201 3883478 3881360 3877445 3865425 3846676 3837821 3847687 3841654 3834800 3829255 3915208 3910848 3897063 3897740 3902217 3902544 3891517 3878943 3868624 3859664 3852055 3848062 3856211 3849827 3897082 3890499 3881820 3881719 3871382 3871673 3878681 3863525 3845217 3836446 3845329 3837626 3831976 3827892 3892898 3890608 3884762 3887506
sum_num_searches_total=247869268
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3946352 3947582 3927887 3928765 3946666 3944255 3937976 3932562 3933057 3926121 3905306 3892355 3895665 3894471 3880739 3880012 3896746 3896413 3933305 3927589 3934002 3930519 3922642 3920737 3916591 3904299 3885781 3876780 3886703 3880677 3873444 3868084 3954579 3950486 3936423 3937206 3941563 3941700 3931059 3917977 3907637 3898588 3891162 3887017 3895420 3888898 3936781 3929860 3921028 3921032 3910206 3910802 3917784 3902684 3883682 3875190 3884317 3876473 3870857 3866556 3932226 3929970 3923824 3926692
average_num_operations_total=3912090
stdev_num_operations_total=3102
sum_num_operations_total=250373762
min_num_operations_total=3866556
max_num_operations_total=3954579
first_time_thread_terminate_by_thread=10000062 10000062 10000062 10000062 10000064 10000064 10000064 10000064 10000063 10000063 10000063 10000063 10000065 10000065 10000064 10000065 10000064 10000064 10000064 10000064 10000064 10000064 10000064 10000064 10000065 10000065 10000065 10000065 10000063 10000062 10000063 10000062 10000062 10000062 10000064 10000064 10000064 10000064 10000064 10000064 10000066 10000066 10000066 10000066 10000063 10000063 10000063 10000063 10000062 10000061 10000061 10000062 10000063 10000063 10000063 10000063 10000065 10000065 10000065 10000065 10000064 10000064 10000064 10000064
min_time_thread_terminate_total=10000061
max_time_thread_terminate_total=10000066
first_time_thread_start_by_thread=5 5 5 5 7 7 7 7 7 7 7 7 4 4 4 4 6 6 6 6 5 5 5 5 7 7 7 7 7 6 6 6 6 6 6 6 8 9 8 8 9 9 9 9 6 5 5 5 5 5 5 5 8 8 8 8 7 8 8 8 8 8 8 8
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=98366274167188
threads_final_size=9836976
final_keysum=98366274167188
final_size=9836976
validate_result=success
Validation OK.
Structural validation OK.

total_find=247869268
total_rq=0
total_inserts=1252120
total_deletes=1252374
total_updates=2504494
total_queries=247869268
total_ops=250373762
find_throughput=24786926
rq_throughput=0
update_throughput=250449
query_throughput=24786926
total_throughput=25037376


total find                    : 247869268
total rq                      : 0
total inserts                 : 1252120
total deletes                 : 1252374
total updates                 : 2504494
total queries                 : 247869268
total ops                     : 250373762
find throughput               : 24786926
rq throughput                 : 0
update throughput             : 250449
query throughput              : 24786926
total throughput              : 25037376

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=399849529320044
total_execution_walltime=13.844s
REALTIME_MAIN_END_PERF_FORMAT=207437.797883543
[time_cmd_output] time_elapsed_sec=13.99, faults_major=0, faults_minor=233645, mem_maxresident_kb=935740, user_cputime=894.34, sys_cputime=6.41, percent_cpu=6438%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2