__step=000057
__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=data000057.txt
__path_data=data/data000057.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207512.948615080
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.021s
prefilling_size=7354086 prefilling_elapsed_millis=1s
prefilling_size=7635002 prefilling_elapsed_millis=1.1s
prefilling_size=7879998 prefilling_elapsed_millis=1.201s
prefilling_size=8099902 prefilling_elapsed_millis=1.301s
prefilling_size=8294807 prefilling_elapsed_millis=1.401s
prefilling_size=8468187 prefilling_elapsed_millis=1.501s
prefilling_size=8621398 prefilling_elapsed_millis=1.601s
prefilling_size=8758850 prefilling_elapsed_millis=1.701s
prefilling_size=8882025 prefilling_elapsed_millis=1.801s
prefilling_size=8993445 prefilling_elapsed_millis=1.901s
prefilling_size=9094500 prefilling_elapsed_millis=2.002s
prefilling_size=9184385 prefilling_elapsed_millis=2.102s
prefilling_size=9264966 prefilling_elapsed_millis=2.202s
prefilling_size=9338172 prefilling_elapsed_millis=2.302s
prefilling_size=9401111 prefilling_elapsed_millis=2.402s
prefilling_size=9459275 prefilling_elapsed_millis=2.502s
prefilling_size=9512587 prefilling_elapsed_millis=2.602s
prefilling_size=9560023 prefilling_elapsed_millis=2.702s
prefilling_size=9601884 prefilling_elapsed_millis=2.803s
prefilling_size=9640709 prefilling_elapsed_millis=2.903s
prefilling_size=9675368 prefilling_elapsed_millis=3.003s
prefilling_size=9706947 prefilling_elapsed_millis=3.104s
prefilling_size=9735379 prefilling_elapsed_millis=3.204s
prefilling_size=9761624 prefilling_elapsed_millis=3.304s
prefilling_size=9785416 prefilling_elapsed_millis=3.404s
prefilling_size=9806109 prefilling_elapsed_millis=3.504s
finished prefilling to size 9806084 for expected size 10000000 keysum=98077525837104, performing 80216395 updates; total_prefilling_elapsed_ms=3605 ms)
pref_size=9806084
pref_millis=3605
prefill_elapsed_ms=3620
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=2052

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207516.610461442
REALTIME_END_PERF_FORMAT=207526.610565472

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

tree_stats_computeWalltime=0.142s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 511 1006 1950 3708 6898 12412 21549 35779 56365 85009 122312 168087 221135 278469 337050 391457 436649 470044 486633 486744 470131 439034 395251 344412 290067 236528 185813 141146 104006 74159 51289 34337 22415 14106 8744 5201 3020 1763 1006 519 307 167 88 44 20 10 5 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 1 8 32 108 308 746 1786 3974 8205 15025 25689 41131 61503 87378 116788 149360 182930 212580 238375 255659 263474 262507 252459 232767 207528 178569 149308 119699 92376 69548 50160 35238 23885 15950 10037 6321 3839 2159 1282 768 389 232 129 66 38 16 9 5 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1014 1982 3816 7206 13158 23335 39753 64570 100034 148001 209218 282638 365847 453838 540817 619579 682624 725008 742403 733605 701541 647710 577179 497595 415097 335121 260845 196382 143707 101449 69575 46300 30056 18781 11522 6859 3922 2288 1287 696 399 217 110 58 26 14 6 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1014 1982 3816 7206 13158 23335 39753 64570 100034 148001 209218 282638 365847 453838 540817 619579 682624 725008 742403 733605 701541 647710 577179 497595 415097 335121 260845 196382 143707 101449 69575 46300 30056 18781 11522 6859 3922 2288 1287 696 399 217 110 58 26 14 6 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.98242 1.96252 1.94147 1.91667 1.86872 1.83014 1.78012 1.72425 1.6763 1.62971 1.5872 1.54752 1.512 1.47935 1.44899 1.42181 1.397 1.37351 1.35278 1.33252 1.31544 1.29745 1.28088 1.2654 1.25127 1.23752 1.2239 1.21176 1.20216 1.1899 1.18025 1.17194 1.16503 1.15554 1.14792 1.1439 1.13151 1.13386 1.12281 1.13753 1.13218 1.12531 1.10138 1.12727 1.10345 1.15385 1.07143 1 1

tree_stats_height=58
tree_stats_numInternals=6447867
tree_stats_numLeaves=3380345
tree_stats_numNodes=9828212
tree_stats_numKeys=9828212

tree_stats_avgDegreeInternal=1.52426
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34394
tree_stats_avgKeyDepth=28.4331

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 98304 194688 380544 732672 1383552 2526336 4480320 7632576 12397440 19206528 28416192 40169856 54266496 70242624 87136896 103836864 118959168 131063808 139201536 142541376 140852160 134695872 124360320 110818368 95538240 79698624 64343232 50082240 37705344 27591744 19478208 13358400 8889600 5770752 3605952 2212224 1316928 753024 439296 247104 133632 76608 41664 21120 11136 4992 2688 1152 192
tree_stats_sizeInBytes=1887016704

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:130198 2:147381 4:25931 8:124
    [2^00, 2^01]: 130198
    (2^01, 2^02]: 147381
    (2^02, 2^03]: 25931
    (2^03, 2^04]: 124
sum_limbo_reclamation_event_size_total=588437
count_limbo_reclamation_event_size_by_thread=4778 4727 4785 4848 4795 4735 4731 4745 4761 4816 4730 4683 4763 4777 4709 4773 4748 4759 4677 4820 4729 4794 4824 4732 4712 4732 4691 4743 4720 4676 4745 4768 4765 4756 4668 4718 4738 4749 4703 4754 4741 4770 4720 4810 4769 4718 4790 4764 4696 4721 4716 4717 4707 4731 4693 4758 4773 4766 4733 4685 4804 4724 4751 4700
count_limbo_reclamation_event_size_total=303634
sum_limbo_reclamation_event_count_total=389824
first_thread_announced_epoch_by_thread=16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286 16286
sum_num_inserts_by_thread=19032 19092 19012 18510 18849 18814 19003 18617 18732 18572 18734 18683 18733 18773 18842 18693 18661 18808 18785 18568 18673 18740 18546 18515 18694 18735 18668 18555 18979 18761 18700 18892 18698 19066 18705 18746 18702 18725 18568 18967 19141 19107 18608 18729 18898 18767 18731 18827 18638 18407 18695 18562 18361 18589 18603 18774 18854 18750 18569 18689 18589 18811 18870 18644
sum_num_inserts_total=1199361
sum_num_deletes_by_thread=18616 18916 18912 19012 18911 18829 18553 18679 18655 18820 18683 18598 18848 19118 18739 18652 18899 18744 18685 18859 18868 18587 18522 18603 18420 18903 18548 18460 18698 18688 18737 18901 19089 18781 18677 18827 18906 18566 18604 18485 18890 18921 18465 18945 19080 18790 18685 18666 18686 18541 18649 18394 18396 18379 18379 18749 19191 18937 18859 18587 18851 18671 18677 18577
sum_num_deletes_total=1198563
sum_num_searches_by_thread=3736912 3755961 3743627 3716531 3738695 3742950 3725311 3722321 3724125 3701749 3699758 3686501 3742613 3723551 3709663 3711887 3733399 3732295 3706910 3713405 3697521 3689290 3679926 3676029 3690929 3685305 3708510 3701372 3722836 3723237 3711459 3711583 3738364 3726499 3706369 3712035 3722095 3715292 3695413 3691332 3753161 3746325 3722518 3721158 3741374 3740169 3716247 3704144 3682713 3675010 3676797 3660370 3670074 3666021 3656743 3721233 3724912 3721696 3699877 3698504 3709723 3722824 3702715 3697694
sum_num_searches_total=237505562
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3774560 3793969 3781551 3754053 3776455 3780593 3762867 3759617 3761512 3739141 3737175 3723782 3780194 3761442 3747244 3749232 3770959 3769847 3744380 3750832 3735062 3726617 3716994 3713147 3728043 3722943 3745726 3738387 3760513 3760686 3748896 3749376 3776151 3764346 3743751 3749608 3759703 3752583 3732585 3728784 3791192 3784353 3759591 3758832 3779352 3777726 3753663 3741637 3720037 3711958 3714141 3697326 3706831 3702989 3693725 3758756 3762957 3759383 3737305 3735780 3747163 3760306 3740262 3734915
average_num_operations_total=3748491
stdev_num_operations_total=2946
sum_num_operations_total=239903486
min_num_operations_total=3693725
max_num_operations_total=3793969
first_time_thread_terminate_by_thread=10000108 10000108 10000108 10000108 10000105 10000105 10000105 10000105 10000104 10000104 10000104 10000104 10000106 10000106 10000106 10000106 10000106 10000106 10000106 10000106 10000104 10000104 10000104 10000103 10000107 10000107 10000106 10000107 10000108 10000108 10000108 10000108 10000105 10000105 10000105 10000105 10000107 10000107 10000107 10000107 10000105 10000105 10000105 10000105 10000104 10000104 10000104 10000104 10000107 10000107 10000107 10000107 10000109 10000109 10000109 10000109 10000104 10000104 10000104 10000104 10000108 10000108 10000108 10000108
min_time_thread_terminate_total=10000103
max_time_thread_terminate_total=10000109
first_time_thread_start_by_thread=8 8 8 8 10 10 10 10 11 11 11 11 11 11 11 11 9 9 9 9 6 7 7 7 13 13 13 13 10 10 10 10 12 12 12 12 10 10 10 10 9 9 9 9 10 10 10 10 10 10 10 10 9 9 9 9 11 11 11 11 8 8 8 8
min_time_thread_start_total=6
max_time_thread_start_total=13
sum_duration_all_ops_total=

threads_final_keysum=98292709628250
threads_final_size=9828212
final_keysum=98292709628250
final_size=9828212
validate_result=success
Validation OK.
Structural validation OK.

total_find=237505562
total_rq=0
total_inserts=1199361
total_deletes=1198563
total_updates=2397924
total_queries=237505562
total_ops=239903486
find_throughput=23750556
rq_throughput=0
update_throughput=239792
query_throughput=23750556
total_throughput=23990348


total find                    : 237505562
total rq                      : 0
total inserts                 : 1199361
total deletes                 : 1198563
total updates                 : 2397924
total queries                 : 237505562
total ops                     : 239903486
find throughput               : 23750556
rq throughput                 : 0
update throughput             : 239792
query throughput              : 23750556
total throughput              : 23990348

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=218142247368745
total_execution_walltime=13.857s
REALTIME_MAIN_END_PERF_FORMAT=207526.807003685
[time_cmd_output] time_elapsed_sec=14.09, faults_major=1, faults_minor=542086, mem_maxresident_kb=2169120, user_cputime=903.59, sys_cputime=5.53, percent_cpu=6452%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1