__step=000442
__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_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=data000442.txt
__path_data=data/data000442.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x55c3099d63c0
addrof(kcasDescriptors[1])=0x55c3099d6cc0
addrof(rdcssDescriptors[0])=0x55c309af63c0
addrof(rdcssDescriptors[1])=0x55c309af6480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=212207.301957399
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.019s
prefilling_size=7279176 prefilling_elapsed_millis=1s
prefilling_size=7583149 prefilling_elapsed_millis=1.1s
prefilling_size=7850286 prefilling_elapsed_millis=1.201s
prefilling_size=8086892 prefilling_elapsed_millis=1.301s
prefilling_size=8295630 prefilling_elapsed_millis=1.401s
prefilling_size=8478669 prefilling_elapsed_millis=1.501s
prefilling_size=8643070 prefilling_elapsed_millis=1.601s
prefilling_size=8787825 prefilling_elapsed_millis=1.701s
prefilling_size=8915186 prefilling_elapsed_millis=1.801s
prefilling_size=9030359 prefilling_elapsed_millis=1.901s
prefilling_size=9133513 prefilling_elapsed_millis=2.002s
prefilling_size=9225081 prefilling_elapsed_millis=2.102s
prefilling_size=9306976 prefilling_elapsed_millis=2.202s
prefilling_size=9378495 prefilling_elapsed_millis=2.302s
prefilling_size=9444511 prefilling_elapsed_millis=2.402s
prefilling_size=9503274 prefilling_elapsed_millis=2.502s
prefilling_size=9556023 prefilling_elapsed_millis=2.602s
prefilling_size=9601707 prefilling_elapsed_millis=2.702s
prefilling_size=9643327 prefilling_elapsed_millis=2.803s
prefilling_size=9679654 prefilling_elapsed_millis=2.903s
prefilling_size=9715263 prefilling_elapsed_millis=3.003s
prefilling_size=9744296 prefilling_elapsed_millis=3.104s
prefilling_size=9771852 prefilling_elapsed_millis=3.204s
prefilling_size=9794044 prefilling_elapsed_millis=3.304s
prefilling_size=9816185 prefilling_elapsed_millis=3.404s
finished prefilling to size 9816216 for expected size 10000000 keysum=98170046982989, performing 80027189 updates; total_prefilling_elapsed_ms=3505 ms)
pref_size=9816216
pref_millis=3505
prefill_elapsed_ms=3518
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=212210.858980826
REALTIME_END_PERF_FORMAT=212220.859020995

###############################################################################
################################## 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.05s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262078 519974 961151 1409350 1361840 739330 185555 14575 73 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 64 3848 68051 417972 1127567 1461182 905679 237434 18402 87
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523822 1029202 1827322 2489407 2200512 1091234 252009 18475 87
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523822 1029202 1827322 2489407 2200512 1091234 252009 18475 87
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99848 1.97214 1.84159 1.59106 1.3369 1.15992 1.0609 1.01548 1.00076 1

tree_stats_height=28
tree_stats_numInternals=5716069
tree_stats_numLeaves=4240286
tree_stats_numNodes=9956355
tree_stats_numKeys=9956355

tree_stats_avgDegreeInternal=1.74182
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42589
tree_stats_avgKeyDepth=21.7122


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:66 4:6971 8:263519 16:227229 32:196 64:125 128:1
    [2^00, 2^01]: 3
    (2^01, 2^02]: 66
    (2^02, 2^03]: 6971
    (2^03, 2^04]: 263519
    (2^04, 2^05]: 227229
    (2^05, 2^06]: 196
    (2^06, 2^07]: 125
    (2^07, 2^08]: 1
sum_limbo_reclamation_event_size_total=7599874
count_limbo_reclamation_event_size_by_thread=7783 7783 7783 7781 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783 7783
count_limbo_reclamation_event_size_total=498110
sum_limbo_reclamation_event_count_total=498240
first_thread_announced_epoch_by_thread=19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710 19710
sum_num_inserts_by_thread=241918 242447 241315 239814 241231 240826 240513 241742 240914 240496 239097 238738 240845 239931 238481 238500 239828 240207 238643 237982 238489 242415 241030 240793 240066 240934 240547 240333 239361 238905 236987 236915 238688 239466 237602 242270 242866 242420 241731 240770 241016 241189 240554 238107 237993 238943 238029 237903 236518 237639 241833 239772 240692 241457 239642 240448 240582 239145 237802 236811 238086 238313 237801 239417
sum_num_inserts_total=15345748
sum_num_deletes_by_thread=243199 243187 240904 239551 241378 239821 240107 241712 240845 240444 240320 240392 240126 239572 239296 238068 239326 239354 237966 237996 238285 241328 241677 240616 240324 239552 239960 240537 240094 239006 238801 237505 238671 238720 238156 242449 242502 242960 241634 240684 240938 241130 240203 238098 238299 239241 238939 236932 237463 237043 241214 240458 242068 240640 239988 239384 240561 239206 238856 236743 238429 238988 238368 240282
sum_num_deletes_total=15350496
sum_num_searches_by_thread=4373440 4361091 4340609 4312124 4344527 4329444 4315345 4352370 4340949 4334120 4318230 4323912 4326267 4323107 4302735 4285268 4300878 4302049 4284416 4278017 4296884 4363326 4343651 4334801 4333437 4328850 4323726 4323168 4315969 4294793 4271830 4274981 4303673 4302122 4286864 4358406 4361191 4354448 4350087 4339075 4340370 4335532 4313924 4287014 4303865 4304975 4286050 4281005 4272532 4275497 4338568 4328541 4335843 4334404 4316820 4317057 4324220 4303069 4283541 4268920 4285926 4296144 4295783 4306433
sum_num_searches_total=276246213
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4858557 4846725 4822828 4791489 4827136 4810091 4795965 4835824 4822708 4815060 4797647 4803042 4807238 4802610 4780512 4761836 4780032 4781610 4761025 4753995 4773658 4847069 4826358 4816210 4813827 4809336 4804233 4804038 4795424 4772704 4747618 4749401 4781032 4780308 4762622 4843125 4846559 4839828 4833452 4820529 4822324 4817851 4794681 4763219 4780157 4783159 4763018 4755840 4746513 4750179 4821615 4808771 4818603 4816501 4796450 4796889 4805363 4781420 4760199 4742474 4762441 4773445 4771952 4786132
average_num_operations_total=4795975
stdev_num_operations_total=3721
sum_num_operations_total=306942457
min_num_operations_total=4742474
max_num_operations_total=4858557
first_time_thread_terminate_by_thread=10000045 10000045 10000045 10000045 10000043 10000043 10000043 10000043 10000044 10000044 10000044 10000044 10000045 10000045 10000045 10000045 10000042 10000041 10000041 10000042 10000044 10000044 10000044 10000044 10000043 10000043 10000043 10000043 10000042 10000042 10000042 10000042 10000043 10000043 10000043 10000043 10000044 10000044 10000043 10000044 10000043 10000042 10000043 10000042 10000042 10000042 10000042 10000042 10000044 10000044 10000044 10000044 10000045 10000045 10000045 10000045 10000043 10000043 10000043 10000043 10000042 10000042 10000042 10000042
min_time_thread_terminate_total=10000041
max_time_thread_terminate_total=10000045
first_time_thread_start_by_thread=6 6 6 6 5 5 5 5 6 6 6 6 6 6 6 6 5 5 5 5 7 7 7 7 7 6 6 7 7 7 7 7 10 10 10 10 9 9 9 9 10 9 9 9 5 5 5 5 8 8 8 8 9 9 9 9 8 8 8 8 11 11 11 11
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=99571816483992
threads_final_size=9956355
final_keysum=99571816483992
final_size=9956355
validate_result=success
Validation OK.
Structural validation OK.

total_find=276246213
total_rq=0
total_inserts=15345748
total_deletes=15350496
total_updates=30696244
total_queries=276246213
total_ops=306942457
find_throughput=27624621
rq_throughput=0
update_throughput=3069624
query_throughput=27624621
total_throughput=30694245


total find                    : 276246213
total rq                      : 0
total inserts                 : 15345748
total deletes                 : 15350496
total updates                 : 30696244
total queries                 : 276246213
total ops                     : 306942457
find throughput               : 27624621
rq throughput                 : 0
update throughput             : 3069624
query throughput              : 27624621
total throughput              : 30694245

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=534620782497628
total_execution_walltime=13.681s
REALTIME_MAIN_END_PERF_FORMAT=212220.983898390
[time_cmd_output] time_elapsed_sec=13.82, faults_major=0, faults_minor=235434, mem_maxresident_kb=942492, user_cputime=879.38, sys_cputime=4.84, percent_cpu=6394%
timeout=false
algorithm=sigouin_int_avl_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2