__step=000730
__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 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000730.txt
__path_data=data/data000730.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215647.920609142
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=50
DEL_FRAC=50
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=50.0 50.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.018s
prefilling_size=7356729 prefilling_elapsed_millis=1s
prefilling_size=7635241 prefilling_elapsed_millis=1.1s
prefilling_size=7881859 prefilling_elapsed_millis=1.201s
prefilling_size=8102163 prefilling_elapsed_millis=1.301s
prefilling_size=8296838 prefilling_elapsed_millis=1.401s
prefilling_size=8470306 prefilling_elapsed_millis=1.501s
prefilling_size=8627213 prefilling_elapsed_millis=1.601s
prefilling_size=8764844 prefilling_elapsed_millis=1.701s
prefilling_size=8889586 prefilling_elapsed_millis=1.801s
prefilling_size=8999731 prefilling_elapsed_millis=1.901s
prefilling_size=9098186 prefilling_elapsed_millis=2.002s
prefilling_size=9186910 prefilling_elapsed_millis=2.102s
prefilling_size=9267060 prefilling_elapsed_millis=2.202s
prefilling_size=9339094 prefilling_elapsed_millis=2.302s
prefilling_size=9404135 prefilling_elapsed_millis=2.402s
prefilling_size=9463642 prefilling_elapsed_millis=2.502s
prefilling_size=9516370 prefilling_elapsed_millis=2.602s
prefilling_size=9563964 prefilling_elapsed_millis=2.702s
prefilling_size=9608336 prefilling_elapsed_millis=2.802s
prefilling_size=9645085 prefilling_elapsed_millis=2.903s
prefilling_size=9680568 prefilling_elapsed_millis=3.003s
prefilling_size=9711907 prefilling_elapsed_millis=3.103s
prefilling_size=9739137 prefilling_elapsed_millis=3.203s
prefilling_size=9764469 prefilling_elapsed_millis=3.304s
prefilling_size=9786681 prefilling_elapsed_millis=3.404s
prefilling_size=9806095 prefilling_elapsed_millis=3.504s
finished prefilling to size 9806131 for expected size 10000000 keysum=98057587551925, performing 79674053 updates; total_prefilling_elapsed_ms=3605 ms)
pref_size=9806131
pref_millis=3605
prefill_elapsed_ms=3623
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=2046

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215651.580929200
REALTIME_END_PERF_FORMAT=215661.581026143

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

tree_stats_computeWalltime=0.195s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 506 991 1911 3625 6645 11801 20206 33499 53134 80384 116319 160719 212246 268158 325565 380607 428545 464978 485332 488736 475490 447674 407807 360360 308296 256239 206412 161168 121542 88201 61572 41104 26651 16688 10049 5846 3087 1614 821 410 202 88 34 22 6 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 12 39 111 336 816 1860 3762 7576 14138 24139 38929 58935 83803 112680 144668 177261 208114 236227 255046 265373 265355 256385 239212 216518 188794 160356 131618 104635 80530 59583 42510 28725 18882 12029 7346 4485 2380 1221 617 301 158 77 19 18 6 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 508 1003 1950 3736 6981 12617 22066 37261 60710 94522 140458 199648 271181 351961 438245 525275 605806 673092 721559 743782 740863 713029 664192 599572 524814 445033 366768 292786 226177 168731 121155 83614 55376 35570 22078 13192 7572 3994 2042 1027 503 246 111 41 24 7 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 508 1003 1950 3736 6981 12617 22066 37261 60710 94522 140458 199648 271181 351961 438245 525275 605806 673092 721559 743782 740863 713029 664192 599572 524814 445033 366768 292786 226177 168731 121155 83614 55376 35570 22078 13192 7572 3994 2042 1027 503 246 111 41 24 7 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99216 1.97835 1.95613 1.9359 1.89829 1.85546 1.81358 1.77291 1.73028 1.68173 1.63556 1.59327 1.55328 1.51521 1.48326 1.4557 1.42873 1.40367 1.3812 1.35818 1.33898 1.32062 1.30366 1.28872 1.27429 1.26054 1.24836 1.2355 1.22204 1.20864 1.1953 1.18193 1.17069 1.16106 1.15153 1.14236 1.13084 1.11978 1.10716 1.10088 1.09056 1.08748 1.0935 1.06306 1.04878 1.04167 1 1

tree_stats_height=56
tree_stats_numInternals=6545801
tree_stats_numLeaves=3455588
tree_stats_numNodes=10001389
tree_stats_numKeys=10001389

tree_stats_avgDegreeInternal=1.52791
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34551
tree_stats_avgKeyDepth=28.7052

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 97536 192576 374400 717312 1340352 2422464 4236672 7154112 11656320 18148224 26967936 38332416 52066752 67576512 84143040 100852800 116314752 129233664 138539328 142806144 142245696 136901568 127524864 115117824 100764288 85446336 70419456 56214912 43425984 32396352 23261760 16053888 10632192 6829440 4238976 2532864 1453824 766848 392064 197184 96576 47232 21312 7872 4608 1344 192
tree_stats_sizeInBytes=1920266688

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:1 8:0 16:1 32:3 64:7158 128:332823 256:558
    (2^02, 2^03]: 1
    (2^03, 2^04]: 0
    (2^04, 2^05]: 1
    (2^05, 2^06]: 3
    (2^06, 2^07]: 7158
    (2^07, 2^08]: 332823
    (2^08, 2^09]: 558
sum_limbo_reclamation_event_size_total=51377374
count_limbo_reclamation_event_size_by_thread=5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321 5321
count_limbo_reclamation_event_size_total=340544
sum_limbo_reclamation_event_count_total=340672
first_thread_announced_epoch_by_thread=14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738 14738
sum_num_inserts_by_thread=1614639 1618859 1612374 1606574 1612776 1622418 1616906 1613072 1617206 1611960 1607386 1614051 1610936 1605519 1603786 1599552 1603277 1603728 1604103 1614949 1611377 1616748 1615333 1613010 1609837 1608677 1601967 1597075 1598014 1600732 1593155 1596559 1615897 1628211 1621552 1619110 1621714 1620873 1615334 1616934 1609340 1608982 1605717 1604086 1604787 1609958 1608215 1602391 1614493 1614658 1611361 1611206 1609348 1610463 1604322 1601020 1605842 1595968 1602327 1605387 1604214 1608811 1606808 1611601
sum_num_inserts_total=103007485
sum_num_deletes_by_thread=1614647 1617119 1615040 1606774 1611101 1621303 1617127 1613528 1618414 1613191 1608431 1611235 1609678 1604824 1604818 1600748 1604612 1602052 1605127 1612630 1609284 1617435 1613637 1615858 1608219 1609070 1600020 1600446 1601368 1603547 1596806 1598176 1614490 1624772 1620302 1619991 1618397 1620929 1617160 1616798 1608608 1608429 1603679 1606244 1604853 1611472 1608821 1601344 1613314 1613483 1610457 1610873 1609852 1610380 1604592 1600651 1603274 1596491 1601698 1601831 1599570 1609746 1609899 1612065
sum_num_deletes_total=103000730
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3229286 3235978 3227414 3213348 3223877 3243721 3234033 3226600 3235620 3225151 3215817 3225286 3220614 3210343 3208604 3200300 3207889 3205780 3209230 3227579 3220661 3234183 3228970 3228868 3218056 3217747 3201987 3197521 3199382 3204279 3189961 3194735 3230387 3252983 3241854 3239101 3240111 3241802 3232494 3233732 3217948 3217411 3209396 3210330 3209640 3221430 3217036 3203735 3227807 3228141 3221818 3222079 3219200 3220843 3208914 3201671 3209116 3192459 3204025 3207218 3203784 3218557 3216707 3223666
average_num_operations_total=3218878
stdev_num_operations_total=1744
sum_num_operations_total=206008215
min_num_operations_total=3189961
max_num_operations_total=3252983
first_time_thread_terminate_by_thread=10000100 10000097 10000097 10000097 10000096 10000096 10000096 10000096 10000097 10000097 10000099 10000097 10000096 10000096 10000098 10000097 10000097 10000098 10000098 10000098 10000099 10000099 10000099 10000099 10000098 10000098 10000098 10000098 10000100 10000100 10000100 10000100 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000099 10000097 10000097 10000097 10000097 10000100 10000100 10000100 10000100 10000098 10000098 10000098 10000098 10000097 10000098 10000098 10000098 10000097 10000097 10000097 10000097
min_time_thread_terminate_total=10000096
max_time_thread_terminate_total=10000100
first_time_thread_start_by_thread=8 8 8 8 9 9 9 9 7 7 7 7 7 7 7 6 10 10 10 10 9 9 9 9 11 11 11 11 11 11 11 11 10 10 10 10 10 10 10 10 9 10 10 9 12 12 12 12 13 13 13 13 11 11 11 11 12 12 12 12 9 9 9 9
min_time_thread_start_total=6
max_time_thread_start_total=13
sum_duration_all_ops_total=

threads_final_keysum=100000939100935
threads_final_size=10001389
final_keysum=100000939100935
final_size=10001389
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=103007485
total_deletes=103000730
total_updates=206008215
total_queries=0
total_ops=206008215
find_throughput=0
rq_throughput=0
update_throughput=20600821
query_throughput=0
total_throughput=20600821


total find                    : 0
total rq                      : 0
total inserts                 : 103007485
total deletes                 : 103000730
total updates                 : 206008215
total queries                 : 0
total ops                     : 206008215
find throughput               : 0
rq throughput                 : 0
update throughput             : 20600821
query throughput              : 0
total throughput              : 20600821

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=0
total_execution_walltime=13.904s
REALTIME_MAIN_END_PERF_FORMAT=215661.825784343
[time_cmd_output] time_elapsed_sec=14.14, faults_major=0, faults_minor=559314, mem_maxresident_kb=2238048, user_cputime=904.81, sys_cputime=7.14, percent_cpu=6449%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2