__step=000396
__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 128 -nprefill 128 -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=data000396.txt
__path_data=data/data000396.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211613.743031606
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=128
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=128
WORK_THREADS=128
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,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189,190,191


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.031s
prefilling_size=8575139 prefilling_elapsed_millis=1s
prefilling_size=8796902 prefilling_elapsed_millis=1.101s
prefilling_size=8983835 prefilling_elapsed_millis=1.201s
prefilling_size=9139620 prefilling_elapsed_millis=1.301s
prefilling_size=9270571 prefilling_elapsed_millis=1.401s
prefilling_size=9379243 prefilling_elapsed_millis=1.501s
prefilling_size=9473220 prefilling_elapsed_millis=1.601s
prefilling_size=9551280 prefilling_elapsed_millis=1.701s
prefilling_size=9616542 prefilling_elapsed_millis=1.801s
prefilling_size=9673115 prefilling_elapsed_millis=1.902s
prefilling_size=9722039 prefilling_elapsed_millis=2.002s
prefilling_size=9763070 prefilling_elapsed_millis=2.102s
prefilling_size=9796975 prefilling_elapsed_millis=2.202s
prefilling_size=9825969 prefilling_elapsed_millis=2.302s
finished prefilling to size 9825985 for expected size 10000000 keysum=98285144790259, performing 82193621 updates; total_prefilling_elapsed_ms=2403 ms)
pref_size=9825985
pref_millis=2403
prefill_elapsed_ms=2423
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=543

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211616.220101370
REALTIME_END_PERF_FORMAT=211626.220192381

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

tree_stats_computeWalltime=0.147s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 248 494 974 1877 3562 6520 11558 19677 31958 49925 74123 105719 144602 189963 240758 294676 346923 394499 432366 457034 468152 465106 448529 419559 379857 334660 285211 235324 188107 145969 109873 80075 56554 38711 25985 16802 10563 6582 4096 2457 1449 803 406 219 117 59 20 9 5 4 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 1 1 8 40 111 347 793 1856 4053 7475 13943 22972 35763 53306 74874 99860 129297 159330 189116 215958 235790 249103 253516 251174 241123 223193 201090 175340 147662 119995 94793 72481 53951 38511 26544 18185 11947 7468 4659 2950 1788 1083 596 296 176 91 49 15 5 2 3 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 249 495 982 1917 3673 6867 12351 21533 36011 57400 88066 128691 180365 243269 315632 394536 476220 553829 621482 672992 703942 714209 702045 670733 620980 557853 486301 410664 335769 265964 204666 152556 110505 77222 52529 34987 22510 14050 8755 5407 3237 1886 1002 515 293 150 69 24 10 6 5 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 126 249 495 982 1917 3673 6867 12351 21533 36011 57400 88066 128691 180365 243269 315632 394536 476220 553829 621482 672992 703942 714209 702045 670733 620980 557853 486301 410664 335769 265964 204666 152556 110505 77222 52529 34987 22510 14050 8755 5407 3237 1886 1002 515 293 150 69 24 10 6 5 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.98413 1.99197 1.98586 1.96029 1.93688 1.89981 1.84913 1.80763 1.75856 1.70651 1.66448 1.61963 1.58004 1.54704 1.51658 1.48721 1.46015 1.43448 1.40984 1.38718 1.36688 1.34954 1.33175 1.31651 1.3003 1.28664 1.27183 1.25797 1.24459 1.23188 1.2207 1.20855 1.19947 1.18703 1.17894 1.17137 1.16315 1.15491 1.15466 1.14974 1.14426 1.135 1.10551 1.10878 1.14369 1.11263 1.06667 1.05797 1.04167 1.1 1.16667 1 1 1

tree_stats_height=61
tree_stats_numInternals=6532974
tree_stats_numLeaves=3442686
tree_stats_numNodes=9975660
tree_stats_numKeys=9975660

tree_stats_avgDegreeInternal=1.52697
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34511
tree_stats_avgKeyDepth=29.2351

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24192 47808 95040 188544 368064 705216 1318464 2371392 4134336 6914112 11020800 16908672 24708672 34630080 46707648 60601344 75750912 91434240 106335168 119324544 129214464 135156864 137128128 134792640 128780736 119228160 107107776 93369792 78847488 64467648 51065088 39295872 29290752 21216960 14826624 10085568 6717504 4321920 2697600 1680960 1038144 621504 362112 192384 98880 56256 28800 13248 4608 1920 1152 960 384 192
tree_stats_sizeInBytes=1915326720

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:3 8:375 16:191387 32:148848 64:377
    (2^01, 2^02]: 1
    (2^02, 2^03]: 3
    (2^03, 2^04]: 375
    (2^04, 2^05]: 191387
    (2^05, 2^06]: 148848
    (2^06, 2^07]: 377
sum_limbo_reclamation_event_size_total=10520913
count_limbo_reclamation_event_size_by_thread=2664 2664 2664 2663 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664 2664
count_limbo_reclamation_event_size_total=340991
sum_limbo_reclamation_event_count_total=341248
first_thread_announced_epoch_by_thread=6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418 6418
sum_num_inserts_by_thread=166961 167612 166680 167049 167468 167047 165944 167312 166675 166289 165642 164943 165051 166341 164359 164690 165437 165008 166295 166280 167055 167161 165994 166424 166620 165640 165191 165725 165322 165136 164642 164963 168285 168358 167214 166430 167340 167512 167259 166923 166091 166418 165463 165727 167186 166357 167635 167127 166584 165483 165463 166257 165755 166314 164598 165407 165737 164335 164564 164658 166544 165742 165089 165190 165090 166289 164832 165427 165964 164550 165902 165181 164344 164320 164441 166138 165884 165757 165256 165063 165790 165964 165012 164003 165555 164749 163973 163460 164130 166195 164960 164546 164698 165822 164511 164681 166189 166094 165643 166368 166263 166079 164876 166503 166669 166521 166521 165934 166779 166003 166294 164762 164747 164680 163778 163897 163824 165710 165601 165573 165745 165719 164666 165278 165606 165178 164509 164935
sum_num_inserts_total=21211432
sum_num_deletes_by_thread=167140 166639 166037 166443 166881 167090 166340 167048 166862 166796 164658 165103 165207 166206 165966 164415 165054 166244 166323 166712 166498 166153 165316 165789 165614 166792 164286 165306 164938 165371 164529 164922 167133 167680 167193 167004 167802 167100 167011 166791 166498 166296 165562 165713 165727 166079 167605 167321 166570 166615 165674 165870 165157 166205 165642 165253 165587 164669 164792 164691 167278 165690 166053 164892 165711 166509 164792 165845 165341 164748 165402 165133 165083 164490 165890 165637 165942 166006 164149 165426 165621 166447 164218 164051 164706 165707 164289 164831 164044 165215 164759 164846 165123 166108 165195 164506 166545 166559 165327 165766 166414 165899 165250 166287 167085 166321 166415 165818 165836 166436 165886 165514 165418 164736 164787 164677 163845 166664 165737 165061 165889 165990 166080 165267 165567 165493 164514 165581
sum_num_deletes_total=21216265
sum_num_searches_by_thread=3000010 3011974 3003315 2990515 3012054 3011463 2995421 3004448 2993672 3001872 2977067 2973291 2979661 2985257 2967603 2968406 2976752 2983754 2997786 2999966 2992614 3002755 2989220 2985128 2983744 2988966 2967172 2974786 2970652 2970490 2962330 2970865 3014825 3020339 3016105 3006745 3022110 3010748 3012438 3007202 2992695 2988885 2982074 2980126 2998122 2987559 3012583 3002055 2985302 2996308 2979143 2988366 2982165 2984634 2969453 2970766 2977792 2965421 2969212 2957095 2997553 2986913 2978015 2977277 2981201 2981198 2977049 2979292 2971555 2968406 2968570 2969389 2970284 2955802 2974509 2981519 2988309 2988452 2972877 2978887 2989511 2986461 2975887 2959379 2963044 2973169 2954022 2955283 2950275 2977715 2967570 2972709 2973485 2984705 2966172 2976153 2997033 2996777 2987677 2976564 2981080 2990995 2972665 2999196 3004895 2996124 2998377 2998420 2996996 2996903 2988227 2977103 2967053 2956639 2948368 2947746 2952503 2988467 2982071 2971315 2985983 2975159 2978755 2970234 2984965 2973771 2967926 2975614
sum_num_searches_total=381831475
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3334111 3346225 3336032 3324007 3346403 3345600 3327705 3338808 3327209 3334957 3307367 3303337 3309919 3317804 3297928 3297511 3307243 3315006 3330404 3332958 3326167 3336069 3320530 3317341 3315978 3321398 3296649 3305817 3300912 3300997 3291501 3300750 3350243 3356377 3350512 3340179 3357252 3345360 3346708 3340916 3325284 3321599 3313099 3311566 3331035 3319995 3347823 3336503 3318456 3328406 3310280 3320493 3313077 3317153 3299693 3301426 3309116 3294425 3298568 3286444 3331375 3318345 3309157 3307359 3312002 3313996 3306673 3310564 3302860 3297704 3299874 3299703 3299711 3284612 3304840 3313294 3320135 3320215 3302282 3309376 3320922 3318872 3305117 3287433 3293305 3303625 3282284 3283574 3278449 3309125 3297289 3302101 3303306 3316635 3295878 3305340 3329767 3329430 3318647 3308698 3313757 3322973 3302791 3331986 3338649 3328966 3331313 3330172 3329611 3329342 3320407 3307379 3297218 3286055 3276933 3276320 3280172 3320841 3313409 3301949 3317617 3306868 3309501 3300779 3316138 3304442 3296949 3306130
average_num_operations_total=3314524
stdev_num_operations_total=1577
sum_num_operations_total=424259172
min_num_operations_total=3276320
max_num_operations_total=3357252
first_time_thread_terminate_by_thread=10000094 10000094 10000094 10000094 10000092 10000092 10000092 10000092 10000093 10000093 10000093 10000093 10000095 10000094 10000094 10000094 10000096 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000093 10000094 10000094 10000094 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000093 10000093 10000093 10000093 10000096 10000096 10000096 10000096 10000094 10000094 10000093 10000093 10000092 10000092 10000092 10000092 10000094 10000094 10000094 10000094 10000095 10000095 10000095 10000095 10000094 10000094 10000094 10000094 10000094 10000092 10000094 10000094 10000093 10000093 10000095 10000093 10000094 10000094 10000095 10000094 10000096 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000094 10000093 10000093 10000093 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000093 10000093 10000092 10000093 10000096 10000096 10000096 10000096 10000094 10000093 10000093 10000094 10000092 10000092 10000094 10000092 10000094 10000094 10000094 10000094 10000095 10000095 10000095 10000095
min_time_thread_terminate_total=10000092
max_time_thread_terminate_total=10000096
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 3 3 3 3 6 6 6 6 6 6 6 6 6 6 6 6 7 7 7 7 7 7 7 7 5 5 5 5 4 4 4 4 5 5 5 5 4 4 4 4 4 4 5 4 5 5 5 5 6 6 6 6 7 7 7 7 4 4 4 4 4 4 4 4 3 3 3 3 6 6 6 6 6 6 6 6 6 7 7 6 7 7 7 7 7 7 7 7 5 5 5 5 4 4 4 4 5 5 5 5 4 4 4 4 4 4 5 4 5 5 5 5 6 6 6 6 7 7 7 7
min_time_thread_start_total=3
max_time_thread_start_total=7
sum_duration_all_ops_total=

threads_final_keysum=99759937463962
threads_final_size=9975660
final_keysum=99759937463962
final_size=9975660
validate_result=success
Validation OK.
Structural validation OK.

total_find=381831475
total_rq=0
total_inserts=21211432
total_deletes=21216265
total_updates=42427697
total_queries=381831475
total_ops=424259172
find_throughput=38183147
rq_throughput=0
update_throughput=4242769
query_throughput=38183147
total_throughput=42425917


total find                    : 381831475
total rq                      : 0
total inserts                 : 21211432
total deletes                 : 21216265
total updates                 : 42427697
total queries                 : 381831475
total ops                     : 424259172
find throughput               : 38183147
rq throughput                 : 0
update throughput             : 4242769
query throughput              : 38183147
total throughput              : 42425917

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=1495280212663204
total_execution_walltime=12.659s
REALTIME_MAIN_END_PERF_FORMAT=211626.402504318
[time_cmd_output] time_elapsed_sec=12.89, faults_major=0, faults_minor=550669, mem_maxresident_kb=2200136, user_cputime=1599.51, sys_cputime=9.65, percent_cpu=12477%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2