__step=000073
__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" ./sigouin_int_bst_kcas_validate.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=data000073.txt
__path_data=data/data000073.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x56346a9c23c0
addrof(kcasDescriptors[1])=0x56346a9c2cc0
addrof(rdcssDescriptors[0])=0x56346aae23c0
addrof(rdcssDescriptors[1])=0x56346aae2480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=207718.368409446
binary=./sigouin_int_bst_kcas_validate.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=sigouin_int_bst_kcas_validate
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=56
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.014s
prefilling_size=7832167 prefilling_elapsed_millis=1s
prefilling_size=8088658 prefilling_elapsed_millis=1.101s
prefilling_size=8313384 prefilling_elapsed_millis=1.201s
prefilling_size=8512561 prefilling_elapsed_millis=1.301s
prefilling_size=8686569 prefilling_elapsed_millis=1.401s
prefilling_size=8838311 prefilling_elapsed_millis=1.502s
prefilling_size=8970220 prefilling_elapsed_millis=1.602s
prefilling_size=9091440 prefilling_elapsed_millis=1.702s
prefilling_size=9197877 prefilling_elapsed_millis=1.802s
prefilling_size=9286504 prefilling_elapsed_millis=1.903s
prefilling_size=9368096 prefilling_elapsed_millis=2.003s
prefilling_size=9442125 prefilling_elapsed_millis=2.103s
prefilling_size=9504102 prefilling_elapsed_millis=2.203s
prefilling_size=9560006 prefilling_elapsed_millis=2.304s
prefilling_size=9609401 prefilling_elapsed_millis=2.404s
prefilling_size=9654952 prefilling_elapsed_millis=2.504s
prefilling_size=9694690 prefilling_elapsed_millis=2.604s
prefilling_size=9729883 prefilling_elapsed_millis=2.704s
prefilling_size=9758965 prefilling_elapsed_millis=2.805s
prefilling_size=9785663 prefilling_elapsed_millis=2.905s
prefilling_size=9808742 prefilling_elapsed_millis=3.005s
finished prefilling to size 9808764 for expected size 10000000 keysum=98098628408665, performing 80402142 updates; total_prefilling_elapsed_ms=3107 ms)
pref_size=9808764
pref_millis=3107
prefill_elapsed_ms=3119
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207721.522984379
REALTIME_END_PERF_FORMAT=207731.523051284

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

tree_stats_computeWalltime=0.145s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 494 959 1825 3391 6125 10800 18462 30389 48141 73181 107107 150087 201446 258709 318766 376741 426865 465584 487520 492495 480415 451228 409104 359250 305029 249830 199256 154340 115542 83297 58321 39727 25947 16469 10104 6007 3426 1954 1094 580 294 154 64 24 10 4 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 7 17 53 137 353 800 1675 3555 6815 12467 21176 34526 52674 76993 105381 138340 173022 204824 233958 253821 264476 267289 257779 239806 215865 187955 156205 126836 100123 76566 55978 39778 27659 18318 11750 7259 4466 2520 1456 837 450 226 126 54 20 7 3 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 501 976 1878 3528 6478 11600 20137 33944 54956 85648 128283 184613 254120 335702 424147 515081 599887 670408 721478 746316 744891 718517 666883 599056 520894 437785 355461 281176 215665 159863 114299 79505 53606 34787 21854 13266 7892 4474 2550 1417 744 380 190 78 30 11 5 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 501 976 1878 3528 6478 11600 20137 33944 54956 85648 128283 184613 254120 335702 424147 515081 599887 670408 721478 746316 744891 718517 666883 599056 520894 437785 355461 281176 215665 159863 114299 79505 53606 34787 21854 13266 7892 4474 2550 1417 744 380 190 78 30 11 5 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.96484 1.96208 1.9416 1.90682 1.875 1.84517 1.80491 1.76883 1.72375 1.68249 1.64335 1.60418 1.56352 1.52832 1.49281 1.46285 1.43323 1.40598 1.3817 1.3587 1.33819 1.31965 1.30014 1.28484 1.26983 1.25486 1.24129 1.23046 1.2181 1.20551 1.19393 1.18534 1.17457 1.16491 1.1548 1.14469 1.14209 1.13279 1.13321 1.12667 1.11574 1.11559 1.09474 1.07368 1.07692 1.03333 1.09091 1 1

tree_stats_height=57
tree_stats_numInternals=6451068
tree_stats_numLeaves=3384405
tree_stats_numNodes=9835473
tree_stats_numKeys=9835473

tree_stats_avgDegreeInternal=1.52463
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3441
tree_stats_avgKeyDepth=28.7485


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:158794 2:179122 4:31324 8:207 16:1
    [2^00, 2^01]: 158794
    (2^01, 2^02]: 179122
    (2^02, 2^03]: 31324
    (2^03, 2^04]: 207
    (2^04, 2^05]: 1
sum_limbo_reclamation_event_size_total=715452
count_limbo_reclamation_event_size_by_thread=5777 5770 5777 5799 5815 5791 5767 5854 5743 5753 5817 5703 5700 5743 5664 5799 5769 5760 5737 5754 5807 5747 5806 5794 5768 5724 5719 5763 5771 5762 5789 5709 5813 5738 5761 5763 5801 5845 5768 5843 5862 5817 5806 5801 5744 5800 5680 5782 5798 5731 5737 5775 5775 5709 5780 5761 5860 5785 5780 5838 5800 5805 5680 5759
count_limbo_reclamation_event_size_total=369448
sum_limbo_reclamation_event_count_total=474304
first_thread_announced_epoch_by_thread=18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956 18956
sum_num_inserts_by_thread=22831 22941 22851 22550 22595 22861 22761 22955 22812 23101 22760 22829 22950 22669 22819 22658 22665 22818 22800 22904 22344 22950 23110 22885 22726 22775 22488 22601 22585 22892 22470 22566 22964 23044 22905 22983 23306 23107 22995 23038 22885 22545 22962 22685 22780 22916 22865 22543 22589 22782 23074 22842 22727 22807 22843 22725 22788 22889 22603 22858 22670 22839 22916 23188
sum_num_inserts_total=1460185
sum_num_deletes_by_thread=22908 22770 23014 22712 22852 22902 22705 22786 22714 22888 22721 22661 22798 22580 22399 22730 22779 22870 22654 22775 22611 22770 22863 22792 23062 23005 22590 22843 22695 22706 22576 22659 22910 22853 22849 22614 22874 22852 22884 22896 23205 22896 23025 22767 22825 23045 22528 22881 22763 22562 22921 22812 22512 22848 22761 22669 23177 22660 22566 22308 22871 22975 22826 22765
sum_num_deletes_total=1458290
sum_num_searches_by_thread=4524949 4521898 4525093 4487063 4507293 4508168 4493547 4516055 4525189 4508849 4500200 4492591 4528564 4508539 4503474 4473149 4497791 4513798 4504895 4483742 4494174 4525426 4530063 4513757 4528880 4530879 4521046 4517947 4508044 4512707 4473689 4485788 4521019 4544106 4513661 4529806 4554233 4551695 4551280 4538194 4557938 4539999 4543094 4523688 4526553 4529682 4517800 4498567 4486033 4503965 4532058 4525284 4512433 4535029 4503347 4500261 4531669 4495121 4476918 4471047 4491661 4506326 4526832 4534198
sum_num_searches_total=288940744
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4570688 4567609 4570958 4532325 4552740 4553931 4539013 4561796 4570715 4554838 4545681 4538081 4574312 4553788 4548692 4518537 4543235 4559486 4550349 4529421 4539129 4571146 4576036 4559434 4574668 4576659 4566124 4563391 4553324 4558305 4518735 4531013 4566893 4590003 4559415 4575403 4600413 4597654 4597159 4584128 4604028 4585440 4589081 4569140 4572158 4575643 4563193 4543991 4531385 4549309 4578053 4570938 4557672 4580684 4548951 4545655 4577634 4540670 4522087 4516213 4537202 4552140 4572574 4580151
average_num_operations_total=4560300
stdev_num_operations_total=2630
sum_num_operations_total=291859219
min_num_operations_total=4516213
max_num_operations_total=4604028
first_time_thread_terminate_by_thread=10000068 10000067 10000067 10000067 10000070 10000070 10000070 10000070 10000068 10000068 10000068 10000068 10000070 10000070 10000070 10000070 10000067 10000067 10000067 10000067 10000068 10000068 10000068 10000068 10000071 10000071 10000071 10000071 10000069 10000069 10000069 10000069 10000071 10000071 10000071 10000071 10000067 10000066 10000066 10000067 10000069 10000069 10000069 10000069 10000068 10000068 10000068 10000068 10000070 10000070 10000070 10000070 10000067 10000067 10000067 10000067 10000066 10000066 10000066 10000066 10000069 10000069 10000069 10000069
min_time_thread_terminate_total=10000066
max_time_thread_terminate_total=10000071
first_time_thread_start_by_thread=6 6 6 6 5 5 5 5 6 6 6 6 7 7 7 7 7 7 7 7 8 8 8 8 7 7 7 7 9 9 9 9 9 9 9 9 6 6 6 6 8 8 8 8 7 7 7 7 9 9 9 9 10 10 10 10 4 4 4 4 8 8 8 8
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=98356793291000
threads_final_size=9835473
final_keysum=98356793291000
final_size=9835473
validate_result=success
Validation OK.
Structural validation OK.

total_find=288940744
total_rq=0
total_inserts=1460185
total_deletes=1458290
total_updates=2918475
total_queries=288940744
total_ops=291859219
find_throughput=28894074
rq_throughput=0
update_throughput=291847
query_throughput=28894074
total_throughput=29185921


total find                    : 288940744
total rq                      : 0
total inserts                 : 1460185
total deletes                 : 1458290
total updates                 : 2918475
total queries                 : 288940744
total ops                     : 291859219
find throughput               : 28894074
rq throughput                 : 0
update throughput             : 291847
query throughput              : 28894074
total throughput              : 29185921

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=377208956664670
total_execution_walltime=13.365s
REALTIME_MAIN_END_PERF_FORMAT=207731.734759819
[time_cmd_output] time_elapsed_sec=13.51, faults_major=0, faults_minor=233895, mem_maxresident_kb=936484, user_cputime=869.45, sys_cputime=4.60, percent_cpu=6467%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1