__step=000746
__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 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000746.txt
__path_data=data/data000746.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x55639b8363c0
addrof(kcasDescriptors[1])=0x55639b836cc0
addrof(rdcssDescriptors[0])=0x55639b9563c0
addrof(rdcssDescriptors[1])=0x55639b956480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=215852.823628779
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=50
DEL_FRAC=50
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=50.0 50.0
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.02s
prefilling_size=7651631 prefilling_elapsed_millis=1s
prefilling_size=7914624 prefilling_elapsed_millis=1.101s
prefilling_size=8145479 prefilling_elapsed_millis=1.201s
prefilling_size=8347361 prefilling_elapsed_millis=1.301s
prefilling_size=8525417 prefilling_elapsed_millis=1.401s
prefilling_size=8683886 prefilling_elapsed_millis=1.501s
prefilling_size=8821340 prefilling_elapsed_millis=1.601s
prefilling_size=8945021 prefilling_elapsed_millis=1.701s
prefilling_size=9054166 prefilling_elapsed_millis=1.801s
prefilling_size=9153263 prefilling_elapsed_millis=1.902s
prefilling_size=9240829 prefilling_elapsed_millis=2.002s
prefilling_size=9320390 prefilling_elapsed_millis=2.102s
prefilling_size=9387868 prefilling_elapsed_millis=2.202s
prefilling_size=9449076 prefilling_elapsed_millis=2.302s
prefilling_size=9504471 prefilling_elapsed_millis=2.402s
prefilling_size=9555302 prefilling_elapsed_millis=2.502s
prefilling_size=9598519 prefilling_elapsed_millis=2.602s
prefilling_size=9638158 prefilling_elapsed_millis=2.703s
prefilling_size=9675573 prefilling_elapsed_millis=2.803s
prefilling_size=9709497 prefilling_elapsed_millis=2.903s
prefilling_size=9735496 prefilling_elapsed_millis=3.003s
prefilling_size=9761524 prefilling_elapsed_millis=3.104s
prefilling_size=9784697 prefilling_elapsed_millis=3.204s
prefilling_size=9806933 prefilling_elapsed_millis=3.304s
finished prefilling to size 9806916 for expected size 10000000 keysum=98075165854301, performing 86387969 updates; total_prefilling_elapsed_ms=3405 ms)
pref_size=9806916
pref_millis=3405
prefill_elapsed_ms=3425
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215856.281257169
REALTIME_END_PERF_FORMAT=215866.281356649

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

tree_stats_computeWalltime=0.266s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1014 1985 3797 7140 12993 22709 38187 61318 93940 136100 187791 247072 309769 370131 422798 464150 490480 500159 490069 464413 424401 375981 323470 269652 217592 169984 129326 96076 69105 47907 32269 21136 13349 8202 4972 2920 1677 999 557 331 205 121 65 23 11 6 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 7 21 97 256 723 1818 4030 8263 15641 28271 46002 69255 99319 134004 169830 203851 233250 256127 269946 273153 266357 249789 225368 198483 168870 139153 110319 84788 64174 47233 33091 22493 14973 9600 5956 3609 2201 1216 751 403 228 147 84 53 16 8 6 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1021 2006 3894 7396 13716 24527 42217 69581 109581 164371 233793 316327 409088 504135 592628 668001 723730 756286 760015 737566 690758 625770 548838 468135 386462 309137 239645 180864 133279 95140 65360 43629 28322 17802 10928 6529 3878 2215 1308 734 433 268 149 76 27 14 8 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1021 2006 3894 7396 13716 24527 42217 69581 109581 164371 233793 316327 409088 504135 592628 668001 723730 756286 760015 737566 690758 625770 548838 468135 386462 309137 239645 180864 133279 95140 65360 43629 28322 17802 10928 6529 3878 2215 1308 734 433 268 149 76 27 14 8 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99414 1.9716 1.95165 1.92424 1.88913 1.84092 1.79537 1.74363 1.69362 1.64273 1.59434 1.54979 1.51218 1.47512 1.44134 1.41376 1.38859 1.36727 1.34359 1.32565 1.30688 1.29152 1.27623 1.26358 1.24952 1.23688 1.22534 1.21506 1.2057 1.19534 1.18345 1.17381 1.16471 1.15723 1.15313 1.14248 1.14673 1.13873 1.1395 1.13532 1.13896 1.1455 1.10448 1.07383 1.05263 1.11111 1.14286 1 1 1

tree_stats_height=59
tree_stats_numInternals=6537378
tree_stats_numLeaves=3463235
tree_stats_numNodes=10000613
tree_stats_numKeys=10000613

tree_stats_avgDegreeInternal=1.52976
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3463
tree_stats_avgKeyDepth=28.1174


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:7 2:0 4:0 8:0 16:0 32:52 64:8263 128:394924 256:175 512:202
    [2^00, 2^01]: 7
    (2^01, 2^02]: 0
    (2^02, 2^03]: 0
    (2^03, 2^04]: 0
    (2^04, 2^05]: 0
    (2^05, 2^06]: 52
    (2^06, 2^07]: 8263
    (2^07, 2^08]: 394924
    (2^08, 2^09]: 175
    (2^09, 2^10]: 202
sum_limbo_reclamation_event_size_total=60675514
count_limbo_reclamation_event_size_by_thread=6307 6306 6307 6306 6306 6306 6306 6307 6306 6306 6306 6307 6307 6306 6306 6307 6307 6307 6307 6307 6307 6307 6307 6306 6307 6306 6307 6306 6307 6307 6307 6306 6307 6307 6307 6306 6307 6307 6307 6307 6307 6306 6306 6306 6307 6307 6307 6307 6307 6307 6307 6307 6307 6306 6306 6307 6307 6307 6306 6306 6306 6307 6306 6306
count_limbo_reclamation_event_size_total=403623
sum_limbo_reclamation_event_count_total=403776
first_thread_announced_epoch_by_thread=17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084 17084
sum_num_inserts_by_thread=1903309 1909864 1905354 1897516 1906558 1908517 1904838 1901683 1902125 1890534 1888661 1897026 1887684 1892023 1886644 1889595 1915090 1914254 1901549 1899333 1905218 1902806 1897663 1893528 1895567 1890640 1880099 1877938 1881524 1890401 1901021 1902289 1919844 1912780 1918421 1914083 1907912 1904876 1902681 1897970 1895103 1892431 1885317 1887282 1910209 1916827 1912812 1909658 1907222 1901166 1895114 1890043 1886983 1886309 1889201 1885532 1887832 1879473 1907359 1899628 1914806 1907108 1915118 1912972
sum_num_inserts_total=121574923
sum_num_deletes_by_thread=1901735 1910843 1905327 1900473 1910370 1908520 1907365 1900409 1904379 1889998 1889211 1893901 1886655 1889823 1885992 1888731 1914802 1912489 1898023 1897669 1902479 1903080 1900514 1896081 1893332 1889703 1876107 1878781 1881294 1889223 1901107 1900869 1921314 1913147 1917948 1916267 1907155 1902939 1902449 1898200 1895244 1893613 1885945 1890379 1908518 1918540 1912356 1911885 1908224 1900370 1896940 1890184 1886234 1888850 1890211 1888637 1889613 1882281 1909109 1900622 1913698 1907271 1912839 1911032
sum_num_deletes_total=121581299
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3805044 3820707 3810681 3797989 3816928 3817037 3812203 3802092 3806504 3780532 3777872 3790927 3774339 3781846 3772636 3778326 3829892 3826743 3799572 3797002 3807697 3805886 3798177 3789609 3788899 3780343 3756206 3756719 3762818 3779624 3802128 3803158 3841158 3825927 3836369 3830350 3815067 3807815 3805130 3796170 3790347 3786044 3771262 3777661 3818727 3835367 3825168 3821543 3815446 3801536 3792054 3780227 3773217 3775159 3779412 3774169 3777445 3761754 3816468 3800250 3828504 3814379 3827957 3824004
average_num_operations_total=3799315
stdev_num_operations_total=2721
sum_num_operations_total=243156222
min_num_operations_total=3756206
max_num_operations_total=3841158
first_time_thread_terminate_by_thread=10000100 10000100 10000101 10000100 10000099 10000099 10000099 10000101 10000099 10000099 10000099 10000099 10000101 10000101 10000101 10000101 10000100 10000101 10000100 10000100 10000099 10000099 10000099 10000099 10000097 10000099 10000097 10000099 10000100 10000100 10000100 10000100 10000100 10000100 10000100 10000100 10000101 10000101 10000101 10000101 10000101 10000101 10000101 10000101 10000098 10000100 10000098 10000098 10000100 10000100 10000100 10000100 10000100 10000099 10000100 10000100 10000098 10000098 10000098 10000098 10000102 10000102 10000102 10000102
min_time_thread_terminate_total=10000097
max_time_thread_terminate_total=10000102
first_time_thread_start_by_thread=5 5 5 5 5 5 5 5 5 5 5 5 7 7 7 7 9 9 9 9 11 11 11 11 8 8 8 8 6 6 6 6 4 4 4 4 12 12 12 12 3 3 3 3 10 10 10 10 4 4 4 4 7 7 7 7 7 7 7 7 6 6 6 6
min_time_thread_start_total=3
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100029781138014
threads_final_size=10000613
final_keysum=100029781138014
final_size=10000613
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=121574923
total_deletes=121581299
total_updates=243156222
total_queries=0
total_ops=243156222
find_throughput=0
rq_throughput=0
update_throughput=24315622
query_throughput=0
total_throughput=24315622


total find                    : 0
total rq                      : 0
total inserts                 : 121574923
total deletes                 : 121581299
total updates                 : 243156222
total queries                 : 0
total ops                     : 243156222
find throughput               : 0
rq throughput                 : 0
update throughput             : 24315622
query throughput              : 0
total throughput              : 24315622

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=0
total_execution_walltime=13.789s
REALTIME_MAIN_END_PERF_FORMAT=215866.613858683
[time_cmd_output] time_elapsed_sec=13.94, faults_major=0, faults_minor=244392, mem_maxresident_kb=978380, user_cputime=884.18, sys_cputime=6.16, percent_cpu=6386%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2