__step=000505
__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 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000505.txt
__path_data=data/data000505.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=212947.311487207
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=2000000
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.019s
prefilling_size=998946 prefilling_elapsed_millis=1s
finished prefilling to size 998942 for expected size 1000000 keysum=999150220346, performing 33949214 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=998942
pref_millis=1102
prefill_elapsed_ms=1112
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=887

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=212948.464954013
REALTIME_END_PERF_FORMAT=212958.465049046

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

tree_stats_computeWalltime=0.119s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 124 242 465 880 1606 2858 4870 7642 11340 15888 21156 26922 32722 38234 43032 46781 49512 50603 49608 46973 42935 37767 31935 25865 20253 15063 10835 7583 4986 3065 1812 978 490 201 82 28 12 5 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 2 11 24 92 202 463 1180 2176 3696 5757 8224 11320 14478 17922 20829 23116 25560 27293 27584 26834 25285 22896 19913 16292 13204 10008 7291 5272 3625 2205 1383 736 398 161 68 24 11 5 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 244 476 904 1698 3060 5333 8822 13516 19584 26913 35146 44042 52712 60954 67610 72628 76163 76901 74557 69769 63052 54831 45778 36545 28267 20843 14874 10258 6690 4017 2361 1226 599 243 96 36 16 6 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 244 476 904 1698 3060 5333 8822 13516 19584 26913 35146 44042 52712 60954 67610 72628 76163 76901 74557 69769 63052 54831 45778 36545 28267 20843 14874 10258 6690 4017 2361 1226 599 243 96 36 16 6 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.96 1.95902 1.92227 1.90487 1.8563 1.80882 1.74105 1.66584 1.60994 1.56296 1.51982 1.48711 1.45388 1.43102 1.40322 1.3823 1.36695 1.34529 1.32443 1.30575 1.28834 1.27063 1.25247 1.2333 1.21929 1.20448 1.19378 1.17984 1.16611 1.1423 1.13667 1.10504 1.08891 1.07012 1.05761 1.08333 1.11111 1.0625 1 1

tree_stats_height=47
tree_stats_numInternals=655481
tree_stats_numLeaves=345542
tree_stats_numNodes=1001023
tree_stats_numKeys=1001023

tree_stats_avgDegreeInternal=1.52716
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34519
tree_stats_avgKeyDepth=24.6341

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24000 46848 91392 173568 326016 587520 1023936 1693824 2595072 3760128 5167296 6748032 8456064 10120704 11703168 12981120 13944576 14623296 14764992 14314944 13395648 12105984 10527552 8789376 7016640 5427264 4001856 2855808 1969536 1284480 771264 453312 235392 115008 46656 18432 6912 3072 1152 192
tree_stats_sizeInBytes=192196416

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:108 4:9589 8:342824 16:286861 32:87 64:342 128:57
    [2^00, 2^01]: 3
    (2^01, 2^02]: 108
    (2^02, 2^03]: 9589
    (2^03, 2^04]: 342824
    (2^04, 2^05]: 286861
    (2^05, 2^06]: 87
    (2^06, 2^07]: 342
    (2^07, 2^08]: 57
sum_limbo_reclamation_event_size_total=9731640
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=639871
sum_limbo_reclamation_event_count_total=873792
first_thread_announced_epoch_by_thread=29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080 29080
sum_num_inserts_by_thread=426359 423994 424945 419942 423242 420431 416508 416227 416929 422016 418070 418178 421181 420050 416149 419389 413948 409942 410278 409503 409446 408586 406938 418239 417287 416482 416693 416585 416622 416538 413539 410554 410844 410953 410175 409460 407765 422559 418249 417748 418685 416483 417799 417669 417525 413938 410883 408915 408222 405727 405574 404476 417268 417612 416471 416514 415795 415565 414589 412595 414510 414282 413530 416024
sum_num_inserts_total=26573194
sum_num_deletes_by_thread=425255 424920 424086 420168 422079 421545 416798 415988 415292 422155 418265 418905 419250 419200 417362 417800 414562 411272 409142 409981 410371 408936 407585 418383 416449 416526 416139 416358 415344 417716 413663 410615 410401 410684 408366 407932 409074 420513 418185 417161 419833 416503 417596 417909 417564 414200 409565 408170 406816 406533 405336 405248 417954 417400 415085 415929 416412 416806 416726 412516 413580 414652 415731 415506
sum_num_deletes_total=26567996
sum_num_searches_by_thread=7663919 7633294 7627508 7553275 7620207 7578627 7502848 7497291 7476089 7585727 7543856 7535598 7572997 7552224 7497977 7537548 7456040 7410749 7383053 7365619 7359103 7360618 7331987 7527697 7492204 7495872 7499533 7492569 7484517 7501109 7442666 7388386 7391482 7398331 7369501 7364109 7353298 7583146 7535689 7518295 7536651 7502623 7504231 7513061 7507146 7452950 7384127 7350762 7333253 7317512 7300483 7277305 7504216 7509833 7483053 7478196 7484528 7495514 7468259 7435803 7446097 7453871 7464749 7488930
sum_num_searches_total=478177711
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=8515533 8482208 8476539 8393385 8465528 8420603 8336154 8329506 8308310 8429898 8380191 8372681 8413428 8391474 8331488 8374737 8284550 8231963 8202473 8185103 8178920 8178140 8146510 8364319 8325940 8328880 8332365 8325512 8316483 8335363 8269868 8209555 8212727 8219968 8188042 8181501 8170137 8426218 8372123 8353204 8375169 8335609 8339626 8348639 8342235 8281088 8204575 8167847 8148291 8129772 8111393 8087029 8339438 8344845 8314609 8310639 8316735 8327885 8299574 8260914 8274187 8282805 8294010 8320460
average_num_operations_total=8301857
stdev_num_operations_total=12091
sum_num_operations_total=531318901
min_num_operations_total=8087029
max_num_operations_total=8515533
first_time_thread_terminate_by_thread=10000097 10000097 10000097 10000097 10000094 10000094 10000094 10000094 10000096 10000096 10000096 10000096 10000098 10000098 10000098 10000098 10000097 10000097 10000097 10000097 10000098 10000098 10000098 10000098 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000095 10000095 10000095 10000095 10000098 10000098 10000098 10000098 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000097 10000097 10000097 10000097 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000098 10000098 10000098 10000098
min_time_thread_terminate_total=10000094
max_time_thread_terminate_total=10000098
first_time_thread_start_by_thread=7 7 7 7 8 8 8 8 8 8 8 8 8 8 8 8 7 7 7 7 7 8 8 7 7 7 7 7 5 5 4 5 10 10 10 10 10 10 10 10 9 9 9 9 9 9 9 9 6 6 6 6 10 10 10 10 9 9 9 9 9 9 9 9
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=1000757693194
threads_final_size=1001023
final_keysum=1000757693194
final_size=1001023
validate_result=success
Validation OK.
Structural validation OK.

total_find=478177711
total_rq=0
total_inserts=26573194
total_deletes=26567996
total_updates=53141190
total_queries=478177711
total_ops=531318901
find_throughput=47817771
rq_throughput=0
update_throughput=5314119
query_throughput=47817771
total_throughput=53131890


total find                    : 478177711
total rq                      : 0
total inserts                 : 26573194
total deletes                 : 26567996
total updates                 : 53141190
total queries                 : 478177711
total ops                     : 531318901
find throughput               : 47817771
rq throughput                 : 0
update throughput             : 5314119
query throughput              : 47817771
total throughput              : 53131890

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=56112840121261
total_execution_walltime=11.365s
REALTIME_MAIN_END_PERF_FORMAT=212958.676907578
[time_cmd_output] time_elapsed_sec=11.48, faults_major=0, faults_minor=124858, mem_maxresident_kb=500352, user_cputime=745.25, sys_cputime=4.45, percent_cpu=6528%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1