__step=000090
__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_avl_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=data000090.txt
__path_data=data/data000090.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x55ddfa72d3c0
addrof(kcasDescriptors[1])=0x55ddfa72dcc0
addrof(rdcssDescriptors[0])=0x55ddfa84d3c0
addrof(rdcssDescriptors[1])=0x55ddfa84d480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=220877.891998829
binary=./sigouin_int_avl_kcas_validate.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=sigouin_int_avl_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.018s
prefilling_size=7228402 prefilling_elapsed_millis=1s
prefilling_size=7541184 prefilling_elapsed_millis=1.1s
prefilling_size=7818378 prefilling_elapsed_millis=1.201s
prefilling_size=8061505 prefilling_elapsed_millis=1.301s
prefilling_size=8277713 prefilling_elapsed_millis=1.401s
prefilling_size=8466768 prefilling_elapsed_millis=1.501s
prefilling_size=8634743 prefilling_elapsed_millis=1.601s
prefilling_size=8783329 prefilling_elapsed_millis=1.701s
prefilling_size=8915137 prefilling_elapsed_millis=1.801s
prefilling_size=9032495 prefilling_elapsed_millis=1.901s
prefilling_size=9134992 prefilling_elapsed_millis=2.002s
prefilling_size=9226987 prefilling_elapsed_millis=2.102s
prefilling_size=9309406 prefilling_elapsed_millis=2.202s
prefilling_size=9381915 prefilling_elapsed_millis=2.302s
prefilling_size=9447641 prefilling_elapsed_millis=2.402s
prefilling_size=9503787 prefilling_elapsed_millis=2.502s
prefilling_size=9555830 prefilling_elapsed_millis=2.602s
prefilling_size=9601785 prefilling_elapsed_millis=2.702s
prefilling_size=9641955 prefilling_elapsed_millis=2.802s
prefilling_size=9678923 prefilling_elapsed_millis=2.903s
prefilling_size=9711233 prefilling_elapsed_millis=3.003s
prefilling_size=9738786 prefilling_elapsed_millis=3.103s
prefilling_size=9766228 prefilling_elapsed_millis=3.203s
prefilling_size=9787757 prefilling_elapsed_millis=3.304s
prefilling_size=9808841 prefilling_elapsed_millis=3.404s
finished prefilling to size 9808837 for expected size 10000000 keysum=98098658094827, performing 81416078 updates; total_prefilling_elapsed_ms=3504 ms)
pref_size=9808837
pref_millis=3504
prefill_elapsed_ms=3517
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=220881.448508109
REALTIME_END_PERF_FORMAT=220891.448583307

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

tree_stats_computeWalltime=0.074s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262003 518141 952002 1405592 1385617 741565 174043 13084 82 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 130 5301 72112 405178 1105919 1495442 914109 222091 16456 98
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523442 1024114 1810770 2491536 2237007 1088152 235175 16538 98
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523442 1024114 1810770 2491536 2237007 1088152 235175 16538 98
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99735 1.96663 1.83855 1.59971 1.34171 1.15493 1.05618 1.01469 1.00097 1

tree_stats_height=28
tree_stats_numInternals=5714272
tree_stats_numLeaves=4236836
tree_stats_numNodes=9951108
tree_stats_numKeys=9951108

tree_stats_avgDegreeInternal=1.74145
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42577
tree_stats_avgKeyDepth=21.7121


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:71 4:7072 8:265494 16:225879 32:67 64:230
    [2^00, 2^01]: 3
    (2^01, 2^02]: 71
    (2^02, 2^03]: 7072
    (2^03, 2^04]: 265494
    (2^04, 2^05]: 225879
    (2^05, 2^06]: 67
    (2^06, 2^07]: 230
sum_limbo_reclamation_event_size_total=7598792
count_limbo_reclamation_event_size_by_thread=7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794 7794
count_limbo_reclamation_event_size_total=498816
sum_limbo_reclamation_event_count_total=498944
first_thread_announced_epoch_by_thread=19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814 19814
sum_num_inserts_by_thread=242983 242712 240622 239783 240838 242457 241429 241508 241948 241814 239141 236703 238305 237494 236498 235835 237576 237705 241004 240220 239147 241837 240991 239594 241472 239265 238759 236254 237197 238336 236535 236253 243796 241388 241017 242111 241881 242396 240793 240624 238594 237901 239010 238502 238149 239326 240856 241137 241135 239678 240531 240565 240647 238552 238408 236817 235906 237927 235774 237165 241121 242028 241862 242117
sum_num_inserts_total=15339929
sum_num_deletes_by_thread=242952 243308 240454 240422 241864 243279 242264 241557 242663 242487 237709 236442 238291 238193 236780 235818 237726 237576 241802 240461 241115 240853 240905 239978 241002 239550 238264 236630 238037 239555 236066 236599 243448 241759 240757 242315 241954 243459 240800 241601 237868 237982 238803 237758 238771 239133 240414 240532 240785 239256 239653 240326 240914 238312 238325 236485 236322 237104 236182 237408 241078 241770 241325 243039
sum_num_deletes_total=15346240
sum_num_searches_by_thread=4378347 4370464 4324265 4328946 4345083 4366691 4356332 4354786 4352223 4346850 4295030 4271751 4298013 4274226 4255771 4255726 4277647 4270326 4335091 4318036 4325801 4332982 4339831 4317452 4335520 4304003 4298313 4266105 4275454 4291895 4254177 4255082 4381676 4353145 4339715 4350252 4342772 4372339 4333250 4344446 4297105 4285910 4302026 4297115 4285175 4300943 4340944 4330691 4342604 4317100 4333721 4328714 4334005 4297513 4290584 4253855 4266122 4261166 4246667 4273198 4342313 4352353 4350885 4354564
sum_num_searches_total=276175087
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4864282 4856484 4805341 4809151 4827785 4852427 4840025 4837851 4836834 4831151 4771880 4744896 4774609 4749913 4729049 4727379 4752949 4745607 4817897 4798717 4806063 4815672 4821727 4797024 4817994 4782818 4775336 4738989 4750688 4769786 4726778 4727934 4868920 4836292 4821489 4834678 4826607 4858194 4814843 4826671 4773567 4761793 4779839 4773375 4762095 4779402 4822214 4812360 4824524 4796034 4813905 4809605 4815566 4774377 4767317 4727157 4738350 4736197 4718623 4747771 4824512 4836151 4834072 4839720
average_num_operations_total=4794707
stdev_num_operations_total=5155
sum_num_operations_total=306861256
min_num_operations_total=4718623
max_num_operations_total=4868920
first_time_thread_terminate_by_thread=10000079 10000079 10000079 10000079 10000075 10000075 10000075 10000075 10000079 10000079 10000079 10000079 10000075 10000075 10000075 10000075 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000078 10000078 10000078 10000078 10000079 10000079 10000079 10000079 10000074 10000074 10000074 10000074 10000077 10000077 10000077 10000077 10000075 10000074 10000075 10000074 10000078 10000079 10000079 10000078 10000077 10000077 10000077 10000077 10000079 10000079 10000079 10000079 10000076 10000076 10000076 10000076 10000078 10000078 10000078 10000078
min_time_thread_terminate_total=10000074
max_time_thread_terminate_total=10000079
first_time_thread_start_by_thread=9 9 9 9 9 9 9 9 9 9 9 9 10 11 10 10 11 11 11 11 11 11 11 11 10 10 10 10 15 15 15 16 12 12 12 12 12 12 12 12 7 8 8 8 11 11 11 11 13 13 13 13 13 13 13 13 14 14 14 14 15 15 15 15
min_time_thread_start_total=7
max_time_thread_start_total=16
sum_duration_all_ops_total=

threads_final_keysum=99535294541647
threads_final_size=9951108
final_keysum=99535294541647
final_size=9951108
validate_result=success
Validation OK.
Structural validation OK.

total_find=276175087
total_rq=0
total_inserts=15339929
total_deletes=15346240
total_updates=30686169
total_queries=276175087
total_ops=306861256
find_throughput=27617508
rq_throughput=0
update_throughput=3068616
query_throughput=27617508
total_throughput=30686125


total find                    : 276175087
total rq                      : 0
total inserts                 : 15339929
total deletes                 : 15346240
total updates                 : 30686169
total queries                 : 276175087
total ops                     : 306861256
find throughput               : 27617508
rq throughput                 : 0
update throughput             : 3068616
query throughput              : 27617508
total throughput              : 30686125

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=577965485569658
total_execution_walltime=13.706s
REALTIME_MAIN_END_PERF_FORMAT=220891.598753514
[time_cmd_output] time_elapsed_sec=13.85, faults_major=0, faults_minor=235311, mem_maxresident_kb=941876, user_cputime=880.60, sys_cputime=4.82, percent_cpu=6391%
timeout=false
algorithm=sigouin_int_avl_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2