__step=000073
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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 5.0 5.0 -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])=0x55c50490b3c0
addrof(kcasDescriptors[1])=0x55c50490bcc0
addrof(rdcssDescriptors[0])=0x55c504a2b3c0
addrof(rdcssDescriptors[1])=0x55c504a2b480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=220661.329665657
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=5
DEL_FRAC=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=5.0 5.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.015s
prefilling_size=7748675 prefilling_elapsed_millis=1s
prefilling_size=8016576 prefilling_elapsed_millis=1.1s
prefilling_size=8251040 prefilling_elapsed_millis=1.201s
prefilling_size=8457028 prefilling_elapsed_millis=1.301s
prefilling_size=8637079 prefilling_elapsed_millis=1.401s
prefilling_size=8796030 prefilling_elapsed_millis=1.501s
prefilling_size=8935404 prefilling_elapsed_millis=1.601s
prefilling_size=9055898 prefilling_elapsed_millis=1.701s
prefilling_size=9163800 prefilling_elapsed_millis=1.801s
prefilling_size=9259301 prefilling_elapsed_millis=1.901s
prefilling_size=9343982 prefilling_elapsed_millis=2.002s
prefilling_size=9417625 prefilling_elapsed_millis=2.102s
prefilling_size=9483818 prefilling_elapsed_millis=2.202s
prefilling_size=9542888 prefilling_elapsed_millis=2.302s
prefilling_size=9595390 prefilling_elapsed_millis=2.402s
prefilling_size=9642115 prefilling_elapsed_millis=2.502s
prefilling_size=9682959 prefilling_elapsed_millis=2.602s
prefilling_size=9721683 prefilling_elapsed_millis=2.702s
prefilling_size=9752642 prefilling_elapsed_millis=2.802s
prefilling_size=9781350 prefilling_elapsed_millis=2.903s
prefilling_size=9806015 prefilling_elapsed_millis=3.003s
finished prefilling to size 9806288 for expected size 10000000 keysum=98072184266231, performing 78717584 updates; total_prefilling_elapsed_ms=3104 ms)
pref_size=9806288
pref_millis=3104
prefill_elapsed_ms=3117
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=220664.475013445
REALTIME_END_PERF_FORMAT=220674.475099204

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

tree_stats_computeWalltime=0.227s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 509 1008 1953 3675 6699 11766 20026 32603 51362 77335 111845 155198 206119 262659 320802 378107 429835 470472 495062 500921 489878 461624 418278 364958 307678 250942 197422 150326 110782 79105 54632 36429 23751 14835 8962 5191 2888 1515 805 396 196 97 61 26 9 1 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 5 38 127 361 907 1933 4035 7408 13787 23143 36977 56193 79997 109253 140595 173335 206346 235581 258612 270020 272229 265314 246674 220876 189968 159035 127504 98826 74132 53766 37743 25333 16829 10609 6586 3829 2218 1143 626 311 150 68 51 23 9 0 0 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 511 1013 1991 3802 7060 12673 21959 36638 58770 91122 134988 192175 262312 342656 430055 518702 603170 676818 730643 759533 759898 733853 683592 611632 528554 440910 356457 277830 209608 153237 108398 74172 49084 31664 19571 11777 6717 3733 1948 1022 507 247 129 77 32 10 1 1 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 511 1013 1991 3802 7060 12673 21959 36638 58770 91122 134988 192175 262312 342656 430055 518702 603170 676818 730643 759533 759898 733853 683592 611632 528554 440910 356457 277830 209608 153237 108398 74172 49084 31664 19571 11777 6717 3733 1948 1022 507 247 129 77 32 10 1 1 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.9863 1.97038 1.92868 1.89032 1.84618 1.80431 1.7565 1.7142 1.67654 1.6327 1.59509 1.55738 1.52051 1.48852 1.46017 1.4339 1.40947 1.3844 1.36197 1.34097 1.32106 1.30247 1.28285 1.26747 1.25207 1.23931 1.22558 1.21338 1.20254 1.19116 1.18026 1.17062 1.16121 1.14957 1.14384 1.12957 1.1258 1.11599 1.1114 1.10861 1.10059 1.12955 1.12403 1.07792 1.03125 1 1 1 1

tree_stats_height=58
tree_stats_numInternals=6519256
tree_stats_numLeaves=3432508
tree_stats_numNodes=9951764
tree_stats_numKeys=9951764

tree_stats_avgDegreeInternal=1.52652
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34491
tree_stats_avgKeyDepth=28.6626


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:2 2:64 4:6751 8:244788 16:205412 32:262 64:63
    [2^00, 2^01]: 2
    (2^01, 2^02]: 64
    (2^02, 2^03]: 6751
    (2^03, 2^04]: 244788
    (2^04, 2^05]: 205412
    (2^05, 2^06]: 262
    (2^06, 2^07]: 63
sum_limbo_reclamation_event_size_total=6953294
count_limbo_reclamation_event_size_by_thread=7146 7146 7146 7145 7145 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146 7146
count_limbo_reclamation_event_size_total=457342
sum_limbo_reclamation_event_count_total=457472
first_thread_announced_epoch_by_thread=18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338 18338
sum_num_inserts_by_thread=222479 221762 220433 219666 221485 221138 219347 219172 219366 219499 217793 218027 220886 220341 219845 219699 220630 220590 219426 219025 218653 219344 217532 218471 217900 218217 219023 218637 219637 219838 219905 218873 222150 219960 219655 219201 220139 219774 219607 219153 219452 221187 220010 221871 221490 220955 220555 220056 219540 218791 217893 216903 219024 218236 217299 220668 219989 220700 219361 219288 219226 220905 219936 220472
sum_num_inserts_total=14060085
sum_num_deletes_by_thread=221111 222381 221023 220852 221796 220437 219355 219273 219768 218461 217630 217160 220115 219900 219451 219606 220866 220966 220959 219054 218474 218451 219103 218086 217233 218427 219652 219586 219677 219892 219067 219565 223043 221115 219653 218968 220604 219618 219772 219003 218660 221031 220218 220799 221304 221445 220718 220655 217784 218345 217694 217862 218854 218404 218394 220091 220153 220889 219971 218806 220021 219815 220391 220241
sum_num_deletes_total=14061698
sum_num_searches_by_thread=3992763 3992908 3981387 3964469 3982099 3972771 3950009 3946983 3936763 3944568 3932417 3915075 3971208 3968433 3952496 3953943 3971562 3970569 3957558 3942508 3943361 3941299 3924903 3929985 3925643 3925500 3954749 3949240 3953219 3961143 3949743 3947375 3991073 3970917 3951603 3948451 3956731 3958549 3943318 3945490 3938079 3989465 3969369 3971404 3978684 3979871 3970173 3967948 3945243 3929581 3918582 3912548 3923338 3927949 3911962 3960559 3957578 3960152 3949904 3953003 3962558 3966913 3966395 3960401
sum_num_searches_total=253044440
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4436353 4437051 4422843 4404987 4425380 4414346 4388711 4385428 4375897 4382528 4367840 4350262 4412209 4408674 4391792 4393248 4413058 4412125 4397943 4380587 4380488 4379094 4361538 4366542 4360776 4362144 4393424 4387463 4392533 4400873 4388715 4385813 4436266 4411992 4390911 4386620 4397474 4397941 4382697 4383646 4376191 4431683 4409597 4414074 4421478 4422271 4411446 4408659 4382567 4366717 4354169 4347313 4361216 4364589 4347655 4401318 4397720 4401741 4389236 4391097 4401805 4407633 4406722 4401114
average_num_operations_total=4393222
stdev_num_operations_total=2808
sum_num_operations_total=281166223
min_num_operations_total=4347313
max_num_operations_total=4437051
first_time_thread_terminate_by_thread=10000088 10000089 10000089 10000089 10000087 10000087 10000087 10000087 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000090 10000086 10000087 10000087 10000086 10000087 10000087 10000087 10000087 10000091 10000091 10000091 10000091 10000089 10000089 10000089 10000089 10000086 10000086 10000086 10000086 10000088 10000088 10000088 10000088 10000089 10000089 10000089 10000089 10000085 10000086 10000086 10000085 10000087 10000087 10000087 10000087 10000086 10000086 10000086 10000086 10000091 10000091 10000091 10000091
min_time_thread_terminate_total=10000085
max_time_thread_terminate_total=10000091
first_time_thread_start_by_thread=4 4 4 4 5 5 5 5 4 4 4 3 5 5 5 5 6 6 6 6 7 7 7 7 7 7 7 7 6 6 6 6 5 5 5 5 6 6 6 6 7 8 7 7 4 4 4 4 6 6 6 6 8 8 8 8 4 4 4 4 5 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99514284454035
threads_final_size=9951764
final_keysum=99514284454035
final_size=9951764
validate_result=success
Validation OK.
Structural validation OK.

total_find=253044440
total_rq=0
total_inserts=14060085
total_deletes=14061698
total_updates=28121783
total_queries=253044440
total_ops=281166223
find_throughput=25304444
rq_throughput=0
update_throughput=2812178
query_throughput=25304444
total_throughput=28116622


total find                    : 253044440
total rq                      : 0
total inserts                 : 14060085
total deletes                 : 14061698
total updates                 : 28121783
total queries                 : 253044440
total ops                     : 281166223
find throughput               : 25304444
rq throughput                 : 0
update throughput             : 2812178
query throughput              : 25304444
total throughput              : 28116622

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=625707850797661
total_execution_walltime=13.443s
REALTIME_MAIN_END_PERF_FORMAT=220674.774016383
[time_cmd_output] time_elapsed_sec=13.60, faults_major=1, faults_minor=235853, mem_maxresident_kb=944304, user_cputime=866.97, sys_cputime=6.61, percent_cpu=6423%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1