__step=000105
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000105.txt
__path_data=data/data000105.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x558a4c35b3c0
addrof(kcasDescriptors[1])=0x558a4c35bcc0
addrof(rdcssDescriptors[0])=0x558a4c47b3c0
addrof(rdcssDescriptors[1])=0x558a4c47b480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=208134.300326113
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=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.018s
prefilling_size=7222423 prefilling_elapsed_millis=1s
prefilling_size=7532175 prefilling_elapsed_millis=1.1s
prefilling_size=7808719 prefilling_elapsed_millis=1.201s
prefilling_size=8052235 prefilling_elapsed_millis=1.301s
prefilling_size=8267301 prefilling_elapsed_millis=1.401s
prefilling_size=8457677 prefilling_elapsed_millis=1.501s
prefilling_size=8627201 prefilling_elapsed_millis=1.601s
prefilling_size=8774927 prefilling_elapsed_millis=1.701s
prefilling_size=8907631 prefilling_elapsed_millis=1.801s
prefilling_size=9023623 prefilling_elapsed_millis=1.901s
prefilling_size=9127948 prefilling_elapsed_millis=2.002s
prefilling_size=9219782 prefilling_elapsed_millis=2.102s
prefilling_size=9298958 prefilling_elapsed_millis=2.202s
prefilling_size=9372486 prefilling_elapsed_millis=2.302s
prefilling_size=9436212 prefilling_elapsed_millis=2.402s
prefilling_size=9492851 prefilling_elapsed_millis=2.502s
prefilling_size=9546162 prefilling_elapsed_millis=2.602s
prefilling_size=9591088 prefilling_elapsed_millis=2.702s
prefilling_size=9630937 prefilling_elapsed_millis=2.802s
prefilling_size=9668671 prefilling_elapsed_millis=2.903s
prefilling_size=9701138 prefilling_elapsed_millis=3.003s
prefilling_size=9730836 prefilling_elapsed_millis=3.103s
prefilling_size=9756566 prefilling_elapsed_millis=3.203s
prefilling_size=9781454 prefilling_elapsed_millis=3.304s
prefilling_size=9800602 prefilling_elapsed_millis=3.404s
finished prefilling to size 9800589 for expected size 10000000 keysum=97992437487018, performing 81003758 updates; total_prefilling_elapsed_ms=3504 ms)
pref_size=9800589
pref_millis=3504
prefill_elapsed_ms=3521
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=208137.851614888
REALTIME_END_PERF_FORMAT=208147.851672054

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262089 520328 974656 1465967 1386015 648694 116067 5010 28 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 54 3483 57172 397962 1201695 1554714 814852 148353 6240 32
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 523811 1031828 1863929 2587710 2203408 930919 153363 6268 32
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 523811 1031828 1863929 2587710 2203408 930919 153363 6268 32
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99999 1.99839 1.9765 1.86184 1.60182 1.31588 1.12809 1.04006 1.0082 1.00064 1

tree_stats_height=28
tree_stats_numInternals=5640997
tree_stats_numLeaves=4184557
tree_stats_numNodes=9825554
tree_stats_numKeys=9825554

tree_stats_avgDegreeInternal=1.74181
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42589
tree_stats_avgKeyDepth=21.6367


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:170595 2:194842 4:34686 8:192 16:1
    [2^00, 2^01]: 170595
    (2^01, 2^02]: 194842
    (2^02, 2^03]: 34686
    (2^03, 2^04]: 192
    (2^04, 2^05]: 1
sum_limbo_reclamation_event_size_total=778918
count_limbo_reclamation_event_size_by_thread=6244 6274 6266 6252 6300 6326 6264 6279 6274 6259 6307 6308 6289 6253 6143 6161 6239 6224 6265 6191 6283 6298 6241 6309 6290 6272 6277 6246 6306 6224 6195 6228 6248 6246 6207 6308 6244 6278 6283 6288 6254 6186 6310 6243 6235 6176 6256 6271 6195 6227 6260 6259 6242 6248 6220 6225 6240 6258 6313 6246 6181 6307 6233 6342
count_limbo_reclamation_event_size_total=400316
sum_limbo_reclamation_event_count_total=511424
first_thread_announced_epoch_by_thread=20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176 20176
sum_num_inserts_by_thread=24966 25166 24590 24609 24862 24634 25072 24320 25033 24601 24583 24661 24657 24667 24370 24660 24753 24575 24881 24725 24470 25081 24989 24711 24765 24855 24507 25108 24953 24754 24548 24502 24640 24664 24768 24999 25025 24797 24894 24837 24888 24987 24542 24183 24885 24358 24461 24636 24322 24679 24755 24711 25075 24720 24923 24676 24818 24734 24368 24293 24207 24646 24029 24702
sum_num_inserts_total=1580850
sum_num_deletes_by_thread=25026 24878 24641 24602 24921 24755 25482 24753 25060 24787 24809 24945 25234 24849 24360 24145 24580 24763 24891 24533 24940 24903 24546 24911 24925 24980 24752 24799 24725 24730 23879 24394 24840 24829 24748 24820 25105 25068 24919 24742 25176 24844 24890 24536 24752 24369 24447 24463 24435 24783 24699 24765 24634 25129 24773 24720 24808 24719 24539 24579 24566 24624 24730 25271
sum_num_deletes_total=1584820
sum_num_searches_by_thread=4921739 4920665 4885165 4868263 4900204 4878295 4933784 4895801 4932227 4908193 4883224 4882315 4916136 4885664 4863444 4858915 4888499 4884605 4871274 4867162 4853634 4940371 4914446 4925926 4901712 4894493 4909919 4904496 4873704 4884620 4832071 4832011 4892054 4893176 4874029 4935098 4961620 4919178 4917350 4914977 4945812 4933431 4870637 4858178 4892867 4859738 4866419 4833799 4848887 4916966 4913911 4884764 4922042 4921178 4906479 4905230 4900526 4874263 4842484 4823410 4836707 4863565 4847811 4928384
sum_num_searches_total=313017947
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4971731 4970709 4934396 4917474 4949987 4927684 4984338 4944874 4982320 4957581 4932616 4931921 4966027 4935180 4912174 4907720 4937832 4933943 4921046 4916420 4903044 4990355 4963981 4975548 4951402 4944328 4959178 4954403 4923382 4934104 4880498 4880907 4941534 4942669 4923545 4984917 5011750 4969043 4967163 4964556 4995876 4983262 4920069 4906897 4942504 4908465 4915327 4882898 4897644 4966428 4963365 4934240 4971751 4971027 4956175 4954626 4950152 4923716 4891391 4872282 4885480 4912835 4896570 4978357
average_num_operations_total=4940369
stdev_num_operations_total=4050
sum_num_operations_total=316183617
min_num_operations_total=4872282
max_num_operations_total=5011750
first_time_thread_terminate_by_thread=10000060 10000060 10000060 10000060 10000058 10000058 10000058 10000058 10000061 10000061 10000061 10000060 10000059 10000059 10000059 10000059 10000061 10000061 10000061 10000061 10000057 10000058 10000057 10000057 10000061 10000061 10000061 10000061 10000059 10000059 10000059 10000059 10000062 10000062 10000062 10000062 10000063 10000063 10000063 10000063 10000062 10000062 10000062 10000062 10000058 10000058 10000058 10000058 10000058 10000058 10000058 10000058 10000060 10000060 10000060 10000060 10000063 10000063 10000063 10000063 10000057 10000056 10000057 10000056
min_time_thread_terminate_total=10000056
max_time_thread_terminate_total=10000063
first_time_thread_start_by_thread=8 8 8 8 6 6 6 6 8 8 8 8 7 7 7 7 7 7 7 7 7 7 7 7 8 8 8 8 6 7 6 6 9 9 9 9 9 9 9 9 11 11 11 11 11 11 11 11 6 6 6 6 10 10 10 10 8 8 8 8 4 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=98233107436742
threads_final_size=9825554
final_keysum=98233107436742
final_size=9825554
validate_result=success
Validation OK.
Structural validation OK.

total_find=313017947
total_rq=0
total_inserts=1580850
total_deletes=1584820
total_updates=3165670
total_queries=313017947
total_ops=316183617
find_throughput=31301794
rq_throughput=0
update_throughput=316567
query_throughput=31301794
total_throughput=31618361


total find                    : 313017947
total rq                      : 0
total inserts                 : 1580850
total deletes                 : 1584820
total updates                 : 3165670
total queries                 : 313017947
total ops                     : 316183617
find throughput               : 31301794
rq throughput                 : 0
update throughput             : 316567
query throughput              : 31301794
total throughput              : 31618361

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=960006464413676
total_execution_walltime=13.69s
REALTIME_MAIN_END_PERF_FORMAT=208147.991178797
[time_cmd_output] time_elapsed_sec=13.84, faults_major=1, faults_minor=233427, mem_maxresident_kb=934300, user_cputime=880.63, sys_cputime=4.68, 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=1