__step=000169
__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 0.5 0.5 -k 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000169.txt
__path_data=data/data000169.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=208887.416399763
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=0.5
DEL_FRAC=0.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=0.5 0.5
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.015s
prefilling_size=999100 prefilling_elapsed_millis=1s
finished prefilling to size 999078 for expected size 1000000 keysum=998198361932, performing 34324019 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=999078
pref_millis=1101
prefill_elapsed_ms=1111
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=885

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=208888.557300331
REALTIME_END_PERF_FORMAT=208898.557393799

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

tree_stats_computeWalltime=0.108s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 237 443 794 1364 2310 3793 6027 9116 13097 17996 23543 29433 35401 41021 46082 49878 51838 51656 49499 45627 39845 33569 26776 20810 15692 11438 8128 5633 3870 2733 1929 1306 893 628 431 311 221 133 82 37 19 10 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 9 19 47 117 223 467 831 1569 2818 4452 6711 9457 12477 15842 19297 22580 25432 27711 28546 27971 27028 24135 21094 17130 13532 10327 7686 5571 3860 2599 1852 1297 895 588 426 304 220 169 90 64 27 13 10 2 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 246 462 841 1481 2533 4260 6858 10685 15915 22448 30254 38890 47878 56863 65379 72458 77270 79367 78045 73598 66873 57704 47870 37940 29224 21765 15814 11204 7730 5332 3781 2603 1788 1216 857 615 441 302 172 101 46 23 13 4 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 126 246 462 841 1481 2533 4260 6858 10685 15915 22448 30254 38890 47878 56863 65379 72458 77270 79367 78045 73598 66873 57704 47870 37940 29224 21765 15814 11204 7730 5332 3781 2603 1788 1216 857 615 441 302 172 101 46 23 13 4 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.96032 1.91463 1.86147 1.81688 1.78933 1.76984 1.71948 1.67921 1.63631 1.58756 1.54606 1.50727 1.47429 1.44826 1.42836 1.40343 1.37804 1.35627 1.33249 1.30878 1.28868 1.26706 1.24783 1.23321 1.22177 1.20781 1.20106 1.19451 1.18717 1.18913 1.19655 1.17826 1.18517 1.18065 1.18832 1.2147 1.21138 1.18367 1.12914 1.11047 1.08911 1.08696 1.13043 1.07692 1 1

tree_stats_height=53
tree_stats_numInternals=653906
tree_stats_numLeaves=345498
tree_stats_numNodes=999404
tree_stats_numKeys=999404

tree_stats_avgDegreeInternal=1.52836
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3457
tree_stats_avgKeyDepth=25.1165

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24192 47232 88704 161472 284352 486336 817920 1316736 2051520 3055680 4310016 5808768 7466880 9192576 10917696 12552768 13911936 14835840 15238464 14984640 14130816 12839616 11079168 9191040 7284480 5611008 4178880 3036288 2151168 1484160 1023744 725952 499776 343296 233472 164544 118080 84672 57984 33024 19392 8832 4416 2496 768 384
tree_stats_sizeInBytes=191885568

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:217088 2:236351 4:38984 8:248 16:12
    [2^00, 2^01]: 217088
    (2^01, 2^02]: 236351
    (2^02, 2^03]: 38984
    (2^03, 2^04]: 248
    (2^04, 2^05]: 12
sum_limbo_reclamation_event_size_total=939435
count_limbo_reclamation_event_size_by_thread=7794 7732 7762 7756 7752 7682 7685 7638 7738 7815 7848 7754 7885 7859 7752 7880 7651 7644 7601 7574 7553 7633 7678 7571 7652 7779 7723 7734 7754 7761 7758 7609 7598 7715 7512 7515 7598 7691 7761 7564 7676 7666 7776 7778 7760 7543 7622 7666 7640 7614 7607 7686 7754 7666 7761 7770 7762 7731 7811 7737 7699 7708 7643 7646
count_limbo_reclamation_event_size_total=492683
sum_limbo_reclamation_event_count_total=845824
first_thread_announced_epoch_by_thread=28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202 28202
sum_num_inserts_by_thread=39958 40069 39100 39722 38998 38977 38585 39158 39815 40124 40639 39737 40612 39744 39834 40321 38883 37695 37927 37571 37992 37807 38441 38969 40044 38723 38779 38663 39204 39378 38821 37642 37507 38176 37389 37350 38264 38379 39038 38576 39205 38432 38680 39531 38518 37678 38774 38467 37688 38579 38367 38894 39547 39416 39399 39418 39822 39529 39724 38828 39112 38745 38620 39069
sum_num_inserts_total=2490653
sum_num_deletes_by_thread=40244 40508 39106 39923 39123 38861 38695 39222 39953 40115 40133 39843 40921 40279 39797 40781 39129 37724 37669 37886 37627 37912 38399 38548 39419 39097 38599 39005 39346 39633 39214 37992 37751 38279 37228 37496 38058 38757 39244 38311 38994 38673 38777 39515 38531 37840 38338 38724 37942 37940 37943 39082 39568 38904 39691 39520 39210 39293 39505 39062 39019 38652 38879 38778
sum_num_deletes_total=2492207
sum_num_searches_by_thread=7996168 7997831 7779217 7856479 7721012 7716008 7702991 7811101 7873423 7937724 8027359 7898441 8072391 7926825 7908165 8012994 7688299 7503656 7478414 7473353 7454097 7461795 7555550 7629840 7843803 7693724 7684987 7680996 7816966 7800236 7731078 7552105 7462644 7566426 7434435 7420911 7549111 7658973 7748224 7604541 7713959 7700410 7689963 7814853 7559680 7475695 7589592 7581649 7474987 7586566 7560920 7696017 7846190 7702880 7820925 7827960 7808286 7801736 7802790 7678727 7685660 7680447 7699590 7717517
sum_num_searches_total=493249292
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=8076370 8078408 7857423 7936124 7799133 7793846 7780271 7889481 7953191 8017963 8108131 7978021 8153924 8006848 7987796 8094096 7766311 7579075 7554010 7548810 7529716 7537514 7632390 7707357 7923266 7771544 7762365 7758664 7895516 7879247 7809113 7627739 7537902 7642881 7509052 7495757 7625433 7736109 7826506 7681428 7792158 7777515 7767420 7893899 7636729 7551213 7666704 7658840 7550617 7663085 7637230 7773993 7925305 7781200 7900015 7906898 7887318 7880558 7882019 7756617 7763791 7757844 7777089 7795364
average_num_operations_total=7784877
stdev_num_operations_total=20707
sum_num_operations_total=498232152
min_num_operations_total=7495757
max_num_operations_total=8153924
first_time_thread_terminate_by_thread=10000098 10000098 10000098 10000098 10000096 10000097 10000097 10000097 10000097 10000097 10000097 10000096 10000096 10000096 10000096 10000096 10000100 10000100 10000100 10000100 10000100 10000100 10000100 10000100 10000096 10000096 10000096 10000096 10000099 10000099 10000099 10000099 10000098 10000098 10000098 10000098 10000098 10000098 10000098 10000098 10000095 10000095 10000095 10000095 10000101 10000101 10000101 10000101 10000099 10000099 10000099 10000099 10000097 10000097 10000097 10000097 10000096 10000096 10000096 10000096 10000100 10000100 10000100 10000100
min_time_thread_terminate_total=10000095
max_time_thread_terminate_total=10000101
first_time_thread_start_by_thread=4 4 4 4 6 6 6 6 3 3 3 3 6 6 6 6 7 6 7 7 5 5 5 5 6 6 6 6 8 8 8 8 5 5 5 5 9 9 9 9 7 8 7 7 4 4 4 4 5 5 5 5 3 3 3 3 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=998802322951
threads_final_size=999404
final_keysum=998802322951
final_size=999404
validate_result=success
Validation OK.
Structural validation OK.

total_find=493249292
total_rq=0
total_inserts=2490653
total_deletes=2492207
total_updates=4982860
total_queries=493249292
total_ops=498232152
find_throughput=49324929
rq_throughput=0
update_throughput=498286
query_throughput=49324929
total_throughput=49823215


total find                    : 493249292
total rq                      : 0
total inserts                 : 2490653
total deletes                 : 2492207
total updates                 : 4982860
total queries                 : 493249292
total ops                     : 498232152
find throughput               : 49324929
rq throughput                 : 0
update throughput             : 498286
query throughput              : 49324929
total throughput              : 49823215

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=76440072297820
total_execution_walltime=11.334s
REALTIME_MAIN_END_PERF_FORMAT=208898.751101766
[time_cmd_output] time_elapsed_sec=11.45, faults_major=0, faults_minor=125664, mem_maxresident_kb=503492, user_cputime=735.22, sys_cputime=4.51, percent_cpu=6458%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1