__step=000058
__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 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000058.txt
__path_data=data/data000058.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207527.99100010
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=20000000
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.02s
prefilling_size=7255041 prefilling_elapsed_millis=1s
prefilling_size=7528556 prefilling_elapsed_millis=1.1s
prefilling_size=7767981 prefilling_elapsed_millis=1.201s
prefilling_size=7980613 prefilling_elapsed_millis=1.301s
prefilling_size=8172116 prefilling_elapsed_millis=1.401s
prefilling_size=8343015 prefilling_elapsed_millis=1.501s
prefilling_size=8496256 prefilling_elapsed_millis=1.601s
prefilling_size=8632760 prefilling_elapsed_millis=1.701s
prefilling_size=8756248 prefilling_elapsed_millis=1.801s
prefilling_size=8867270 prefilling_elapsed_millis=1.901s
prefilling_size=8968404 prefilling_elapsed_millis=2.002s
prefilling_size=9060362 prefilling_elapsed_millis=2.102s
prefilling_size=9142233 prefilling_elapsed_millis=2.202s
prefilling_size=9217308 prefilling_elapsed_millis=2.302s
prefilling_size=9283660 prefilling_elapsed_millis=2.402s
prefilling_size=9345862 prefilling_elapsed_millis=2.502s
prefilling_size=9403212 prefilling_elapsed_millis=2.602s
prefilling_size=9453155 prefilling_elapsed_millis=2.702s
prefilling_size=9499303 prefilling_elapsed_millis=2.802s
prefilling_size=9540593 prefilling_elapsed_millis=2.903s
prefilling_size=9577510 prefilling_elapsed_millis=3.003s
prefilling_size=9611746 prefilling_elapsed_millis=3.103s
prefilling_size=9644859 prefilling_elapsed_millis=3.203s
prefilling_size=9674502 prefilling_elapsed_millis=3.304s
prefilling_size=9701290 prefilling_elapsed_millis=3.404s
prefilling_size=9725451 prefilling_elapsed_millis=3.504s
prefilling_size=9748061 prefilling_elapsed_millis=3.604s
prefilling_size=9769427 prefilling_elapsed_millis=3.704s
prefilling_size=9788704 prefilling_elapsed_millis=3.804s
prefilling_size=9806710 prefilling_elapsed_millis=3.904s
finished prefilling to size 9806728 for expected size 10000000 keysum=98056363567664, performing 88760233 updates; total_prefilling_elapsed_ms=4005 ms)
pref_size=9806728
pref_millis=4005
prefill_elapsed_ms=4024
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=2272

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207531.153944080
REALTIME_END_PERF_FORMAT=207541.154020893

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

tree_stats_computeWalltime=0.13s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 505 981 1897 3591 6629 11780 20167 32888 51166 76424 109806 151234 200468 255860 313812 370076 420995 461719 486773 495021 483897 455756 413222 361599 304944 249150 197357 150816 111639 79889 54897 37005 24139 15495 9700 5827 3377 1949 1084 590 313 161 90 45 22 8 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 5 17 34 113 318 827 1858 4035 7948 13943 23168 36625 54851 77794 105634 137019 169686 201498 231065 252307 266414 269007 262232 244108 219284 188615 156796 126892 98887 74484 54405 37861 25849 16961 10928 7010 4296 2469 1453 819 440 245 126 69 34 22 9 3 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 998 1931 3704 6947 12607 22025 36923 59114 90367 132974 187859 255319 333654 419446 507095 590681 663217 717838 747328 750311 724763 675454 605707 524228 437765 354153 277708 210526 154373 109302 74866 49988 32456 20628 12837 7673 4418 2537 1409 753 406 216 114 56 30 12 4 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 998 1931 3704 6947 12607 22025 36923 59114 90367 132974 187859 255319 333654 419446 507095 590681 663217 717838 747328 750311 724763 675454 605707 524228 437765 354153 277708 210526 154373 109302 74866 49988 32456 20628 12837 7673 4418 2537 1409 753 406 216 114 56 30 12 4 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.96667 1.9519 1.93578 1.90605 1.86052 1.81264 1.76077 1.71029 1.66314 1.62578 1.58698 1.55406 1.52165 1.49029 1.46081 1.43504 1.41007 1.38618 1.36297 1.3416 1.32102 1.30313 1.28497 1.26849 1.25336 1.23986 1.22688 1.21501 1.20299 1.19053 1.1827 1.17342 1.16638 1.15815 1.15207 1.1438 1.13567 1.13309 1.1281 1.11568 1.12351 1.13547 1.11111 1.09649 1.14286 1.13333 1.08333 1 1

tree_stats_height=58
tree_stats_numInternals=6435278
tree_stats_numLeaves=3388464
tree_stats_numNodes=9823742
tree_stats_numKeys=9823742

tree_stats_avgDegreeInternal=1.52655
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34493
tree_stats_avgKeyDepth=28.7077

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97920 191616 370752 711168 1333824 2420544 4228800 7089216 11349888 17350464 25531008 36068928 49021248 64061568 80533632 97362240 113410752 127337664 137824896 143486976 144059712 139154496 129687168 116295744 100651776 84050880 67997376 53319936 40420992 29639616 20985984 14374272 9597696 6231552 3960576 2464704 1473216 848256 487104 270528 144576 77952 41472 21888 10752 5760 2304 768 192
tree_stats_sizeInBytes=1886158464

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:130247 2:147732 4:25988 8:148
    [2^00, 2^01]: 130247
    (2^01, 2^02]: 147732
    (2^02, 2^03]: 25988
    (2^03, 2^04]: 148
sum_limbo_reclamation_event_size_total=590108
count_limbo_reclamation_event_size_by_thread=4779 4791 4797 4753 4746 4802 4702 4747 4816 4755 4693 4722 4779 4810 4765 4735 4699 4765 4777 4757 4745 4763 4826 4752 4723 4745 4741 4779 4757 4733 4683 4782 4726 4718 4794 4732 4741 4836 4804 4809 4768 4759 4729 4709 4716 4712 4777 4773 4764 4719 4785 4728 4768 4772 4660 4708 4714 4760 4688 4750 4712 4768 4722 4775
count_limbo_reclamation_event_size_total=304115
sum_limbo_reclamation_event_count_total=390208
first_thread_announced_epoch_by_thread=16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738 16738
sum_num_inserts_by_thread=18652 18759 18577 18746 18802 18724 19248 19054 18800 18954 18799 18897 18441 18525 18711 18614 18997 18466 18757 18711 18772 18374 18694 18894 19004 19166 18766 18967 18672 18918 18459 18352 18857 18657 18447 18636 19250 18649 18780 18781 18706 18819 18651 18478 18776 18599 18776 18509 18695 18766 18554 18763 18644 18827 18668 18670 18618 18854 18693 18775 18317 18576 18791 18650
sum_num_inserts_total=1198504
sum_num_deletes_by_thread=18880 18670 18727 18823 19053 18995 18924 18967 18701 18990 18694 18766 18591 18785 18802 18339 18700 18532 18646 18903 18621 18828 18858 18737 18776 18878 18874 18942 18724 18683 18454 18357 19042 19022 18604 18788 18899 18777 18755 18867 18585 19029 18474 18737 18535 18856 18579 18643 18396 18713 18685 18693 18630 18610 18699 18573 18737 18896 18806 18612 18448 18477 18803 18752
sum_num_deletes_total=1198942
sum_num_searches_by_thread=3731498 3733505 3714583 3734951 3757872 3716497 3745636 3742318 3722709 3755204 3706816 3743552 3709300 3709391 3699806 3691148 3736360 3692748 3682345 3715826 3684576 3681587 3716222 3709720 3746506 3744442 3735411 3733745 3702832 3691239 3673317 3667086 3739418 3735769 3688835 3723478 3775948 3732093 3719287 3718658 3719724 3755234 3708964 3735649 3694042 3724208 3677713 3674005 3660574 3695005 3697077 3692494 3696060 3696557 3721319 3685083 3693433 3729076 3710057 3662172 3668960 3676383 3710653 3675866
sum_num_searches_total=237522542
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3769030 3770934 3751887 3772520 3795727 3754216 3783808 3780339 3760210 3793148 3744309 3781215 3746332 3746701 3737319 3728101 3774057 3729746 3719748 3753440 3721969 3718789 3753774 3747351 3784286 3782486 3773051 3771654 3740228 3728840 3710230 3703795 3777317 3773448 3725886 3760902 3814097 3769519 3756822 3756306 3757015 3793082 3746089 3772864 3731353 3761663 3715068 3711157 3697665 3732484 3734316 3729950 3733334 3733994 3758686 3722326 3730788 3766826 3747556 3699559 3705725 3713436 3748247 3713268
average_num_operations_total=3748749
stdev_num_operations_total=3380
sum_num_operations_total=239919988
min_num_operations_total=3697665
max_num_operations_total=3814097
first_time_thread_terminate_by_thread=10000080 10000080 10000080 10000080 10000081 10000081 10000081 10000081 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000084 10000084 10000084 10000084 10000083 10000083 10000083 10000083 10000082 10000082 10000082 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000083 10000083 10000083 10000083 10000080 10000080 10000080 10000080 10000082 10000082 10000082 10000082 10000083 10000083 10000083 10000083 10000080 10000080 10000080 10000081
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000084
first_time_thread_start_by_thread=5 5 5 5 5 5 5 5 6 6 6 6 6 6 6 6 7 7 7 7 7 7 7 7 8 8 8 8 6 6 6 6 3 3 3 3 5 5 5 5 4 4 4 4 4 4 4 4 7 6 7 6 4 4 4 4 4 4 4 4 7 7 7 7
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=98238488635452
threads_final_size=9823742
final_keysum=98238488635452
final_size=9823742
validate_result=success
Validation OK.
Structural validation OK.

total_find=237522542
total_rq=0
total_inserts=1198504
total_deletes=1198942
total_updates=2397446
total_queries=237522542
total_ops=239919988
find_throughput=23752254
rq_throughput=0
update_throughput=239744
query_throughput=23752254
total_throughput=23991998


total find                    : 237522542
total rq                      : 0
total inserts                 : 1198504
total deletes                 : 1198942
total updates                 : 2397446
total queries                 : 237522542
total ops                     : 239919988
find throughput               : 23752254
rq throughput                 : 0
update throughput             : 239744
query throughput              : 23752254
total throughput              : 23991998

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=312006074097166
total_execution_walltime=14.237s
REALTIME_MAIN_END_PERF_FORMAT=207541.337245429
[time_cmd_output] time_elapsed_sec=14.47, faults_major=0, faults_minor=541924, mem_maxresident_kb=2168488, user_cputime=920.72, sys_cputime=6.54, percent_cpu=6405%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2