__step=000394
__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" ./drachsler_pext_bst_lock.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=data000394.txt
__path_data=data/data000394.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211586.629699515
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=drachsler_pext_bst_lock
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=192
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.02s
prefilling_size=7377066 prefilling_elapsed_millis=1s
prefilling_size=7655621 prefilling_elapsed_millis=1.1s
prefilling_size=7902398 prefilling_elapsed_millis=1.201s
prefilling_size=8121215 prefilling_elapsed_millis=1.301s
prefilling_size=8312909 prefilling_elapsed_millis=1.401s
prefilling_size=8485883 prefilling_elapsed_millis=1.501s
prefilling_size=8639874 prefilling_elapsed_millis=1.601s
prefilling_size=8777483 prefilling_elapsed_millis=1.701s
prefilling_size=8901182 prefilling_elapsed_millis=1.801s
prefilling_size=9010530 prefilling_elapsed_millis=1.901s
prefilling_size=9112270 prefilling_elapsed_millis=2.002s
prefilling_size=9199240 prefilling_elapsed_millis=2.102s
prefilling_size=9280144 prefilling_elapsed_millis=2.202s
prefilling_size=9353132 prefilling_elapsed_millis=2.302s
prefilling_size=9417909 prefilling_elapsed_millis=2.402s
prefilling_size=9474404 prefilling_elapsed_millis=2.502s
prefilling_size=9527642 prefilling_elapsed_millis=2.602s
prefilling_size=9572543 prefilling_elapsed_millis=2.702s
prefilling_size=9613626 prefilling_elapsed_millis=2.802s
prefilling_size=9650572 prefilling_elapsed_millis=2.903s
prefilling_size=9685644 prefilling_elapsed_millis=3.003s
prefilling_size=9715961 prefilling_elapsed_millis=3.103s
prefilling_size=9742920 prefilling_elapsed_millis=3.203s
prefilling_size=9768959 prefilling_elapsed_millis=3.303s
prefilling_size=9792012 prefilling_elapsed_millis=3.404s
prefilling_size=9814226 prefilling_elapsed_millis=3.504s
finished prefilling to size 9814237 for expected size 10000000 keysum=98153025094908, performing 80124599 updates; total_prefilling_elapsed_ms=3605 ms)
pref_size=9814237
pref_millis=3605
prefill_elapsed_ms=3624
6node_tIxPvE_object_size=192
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=2052

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211590.287011893
REALTIME_END_PERF_FORMAT=211600.287078560

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

tree_stats_computeWalltime=0.184s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 253 497 969 1858 3495 6404 11412 19445 32075 50827 77492 114102 161400 218184 282432 349397 412897 466790 503132 520481 516634 491939 452288 400636 342031 282224 224729 172166 127911 91771 63572 43345 28436 17965 11128 6596 3901 2178 1171 595 282 126 51 21 9 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 3 13 51 130 341 766 1878 3706 7216 13115 21953 36131 56039 82521 115093 152201 190590 227736 256701 276568 285131 279167 264211 239909 210509 176750 144514 112645 84973 62189 43227 30040 20080 12840 8032 4743 2840 1614 900 457 228 102 38 14 7 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 500 982 1909 3625 6745 12178 21323 35781 58043 90607 136055 197531 274223 364953 464490 565098 657380 730868 777182 793202 777070 731455 664847 581940 492733 401479 316680 240556 176744 125761 86572 58476 38045 23968 14628 8644 5018 2785 1495 739 354 153 59 23 10 4
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 500 982 1909 3625 6745 12178 21323 35781 58043 90607 136055 197531 274223 364953 464490 565098 657380 730868 777182 793202 777070 731455 664847 581940 492733 401479 316680 240556 176744 125761 86572 58476 38045 23968 14628 8644 5018 2785 1495 739 354 153 59 23 10 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.96863 1.97 1.95723 1.92562 1.89655 1.85604 1.81384 1.76612 1.72575 1.68535 1.64634 1.6132 1.57117 1.53522 1.49885 1.46438 1.43264 1.40171 1.37497 1.35091 1.32834 1.30823 1.2906 1.2727 1.25896 1.24203 1.22903 1.21596 1.203 1.19231 1.18289 1.17478 1.16432 1.15779 1.14603 1.14001 1.12922 1.12096 1.11634 1.09632 1.09743 1.07627 1.05229 1.0339 1.04348 1.1 1

tree_stats_height=55
tree_stats_numInternals=6515505
tree_stats_numLeaves=3427918
tree_stats_numNodes=9943423
tree_stats_numKeys=9943423

tree_stats_avgDegreeInternal=1.52612
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34474
tree_stats_avgKeyDepth=28.3116

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 96000 188544 366528 696000 1295040 2338176 4094016 6869952 11144256 17396544 26122560 37925952 52650816 70070976 89182080 108498816 126216960 140326656 149218944 152294784 149197440 140439360 127650624 111732480 94604736 77083968 60802560 46186752 33934848 24146112 16621824 11227392 7304640 4601856 2808576 1659648 963456 534720 287040 141888 67968 29376 11328 4416 1920 768
tree_stats_sizeInBytes=1909137216

6node_tIxPvE_object_size=192
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=8090


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:54 4:5812 8:206535 16:173513 32:367 64:17
    [2^00, 2^01]: 3
    (2^01, 2^02]: 54
    (2^02, 2^03]: 5812
    (2^03, 2^04]: 206535
    (2^04, 2^05]: 173513
    (2^05, 2^06]: 367
    (2^06, 2^07]: 17
sum_limbo_reclamation_event_size_total=5873433
count_limbo_reclamation_event_size_by_thread=6036 6036 6036 6034 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6036 6035
count_limbo_reclamation_event_size_total=386301
sum_limbo_reclamation_event_count_total=386432
first_thread_announced_epoch_by_thread=16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180 16180
sum_num_inserts_by_thread=188284 187856 186736 185803 186329 186205 186838 186397 187300 186706 185283 185822 186844 186711 184942 185291 185290 185295 184590 184081 184492 184822 185884 185431 185542 186224 184934 185042 185956 184837 184063 183903 185982 186301 185241 183746 187739 187461 186703 187246 186903 187168 186854 186221 186092 185259 184571 184531 184533 184235 183515 185745 186950 186073 184734 184299 184921 186008 184481 185076 183959 185095 184894 185219
sum_num_inserts_total=11881488
sum_num_deletes_by_thread=186972 187039 186595 186143 186674 186612 186512 186724 187667 186995 186128 186013 186144 186705 185227 185438 184926 185009 184074 183605 185239 184142 186735 185946 185426 185961 185602 185392 185966 184239 183887 184154 186170 186595 185790 185111 188027 187142 186642 186674 186450 187371 186106 185454 186528 184997 185056 185394 183751 183481 183260 186005 186499 185936 185157 185002 185209 185674 184866 183352 184559 183946 185132 185208
sum_num_deletes_total=11880435
sum_num_searches_by_thread=3382692 3383724 3358947 3348062 3358803 3352131 3350694 3346671 3366405 3363479 3343407 3352112 3359286 3358324 3341183 3341810 3332476 3332904 3316809 3313594 3328517 3315990 3358102 3353916 3337829 3345605 3337427 3335923 3343540 3327595 3309910 3310470 3353795 3348412 3332349 3329808 3388374 3376073 3362704 3370499 3359691 3368287 3359127 3349894 3348917 3339395 3324361 3331246 3311038 3308528 3298295 3342873 3356758 3344063 3336537 3334701 3340777 3338500 3322142 3311994 3321158 3334136 3330460 3324673
sum_num_searches_total=213907902
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3757948 3758619 3732278 3720008 3731806 3724948 3724044 3719792 3741372 3737180 3714818 3723947 3732274 3731740 3711352 3712539 3702692 3703208 3685473 3681280 3698248 3684954 3730721 3725293 3708797 3717790 3707963 3706357 3715462 3696671 3677860 3678527 3725947 3721308 3703380 3698665 3764140 3750676 3736049 3744419 3733044 3742826 3732087 3721569 3721537 3709651 3693988 3701171 3679322 3676244 3665070 3714623 3730207 3716072 3706428 3704002 3710907 3710182 3691489 3680422 3689676 3703177 3700486 3695100
average_num_operations_total=3713591
stdev_num_operations_total=2745
sum_num_operations_total=237669825
min_num_operations_total=3665070
max_num_operations_total=3764140
first_time_thread_terminate_by_thread=10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000070 10000070 10000070 10000070 10000073 10000073 10000073 10000073 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000070 10000069 10000070 10000073 10000073 10000073 10000073 10000068 10000068 10000068 10000068 10000068 10000068 10000068 10000068 10000071 10000071 10000071 10000071 10000070 10000070 10000070 10000070 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000074 10000074 10000074 10000074
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000074
first_time_thread_start_by_thread=10 10 10 10 10 10 10 10 10 10 10 10 11 11 11 11 14 14 14 14 14 14 14 14 12 12 12 12 13 13 13 13 9 9 9 10 13 13 13 13 10 10 10 10 13 13 13 13 14 13 14 14 13 12 13 13 11 11 11 11 9 9 9 9
min_time_thread_start_total=9
max_time_thread_start_total=14
sum_duration_all_ops_total=

threads_final_keysum=99421623829446
threads_final_size=9943423
final_keysum=99421623829446
final_size=9943423
validate_result=success
Validation OK.
Structural validation OK.

total_find=213907902
total_rq=0
total_inserts=11881488
total_deletes=11880435
total_updates=23761923
total_queries=213907902
total_ops=237669825
find_throughput=21390790
rq_throughput=0
update_throughput=2376192
query_throughput=21390790
total_throughput=23766982


total find                    : 213907902
total rq                      : 0
total inserts                 : 11881488
total deletes                 : 11880435
total updates                 : 23761923
total queries                 : 213907902
total ops                     : 237669825
find throughput               : 21390790
rq throughput                 : 0
update throughput             : 2376192
query throughput              : 21390790
total throughput              : 23766982

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=396454761662680
total_execution_walltime=13.904s
REALTIME_MAIN_END_PERF_FORMAT=211600.534363365
[time_cmd_output] time_elapsed_sec=14.14, faults_major=0, faults_minor=546881, mem_maxresident_kb=2188396, user_cputime=900.99, sys_cputime=6.54, percent_cpu=6417%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2