marbl / canu

A single molecule sequence assembler for genomes large and small.
http://canu.readthedocs.io/
654 stars 179 forks source link

job 00001.red FAILED #895

Closed damientully closed 6 years ago

damientully commented 6 years ago

Hi,

I am using the following command:

./canu -useGrid=0 -p BC05 -d /n/scratch2/dct7/Run11_Canu/BC05_AC347 genomeSize=9000 contigFilter="2 1000 1.0 1.0 2" -nanopore-raw /n/scratch2/dct7/Run11_Canu/BC05.fastq and get the following error

Restoring modules from user's modules
-- Canu snapshot v1.7 +167 changes (r8859 000ddc276b06b3fc62ef23b953c4fee831f3457a)
--
-- CITATIONS
--
-- Koren S, Walenz BP, Berlin K, Miller JR, Phillippy AM.
-- Canu: scalable and accurate long-read assembly via adaptive k-mer weighting and repeat separation.
-- Genome Res. 2017 May;27(5):722-736.
-- http://doi.org/10.1101/gr.215087.116
-- 
-- Read and contig alignments during correction, consensus and GFA building use:
--   Šošic M, Šikic M.
--   Edlib: a C/C ++ library for fast, exact sequence alignment using edit distance.
--   Bioinformatics. 2017 May 1;33(9):1394-1395.
--   http://doi.org/10.1093/bioinformatics/btw753
-- 
-- Overlaps are generated using:
--   Berlin K, et al.
--   Assembling large genomes with single-molecule sequencing and locality-sensitive hashing.
--   Nat Biotechnol. 2015 Jun;33(6):623-30.
--   http://doi.org/10.1038/nbt.3238
-- 
--   Myers EW, et al.
--   A Whole-Genome Assembly of Drosophila.
--   Science. 2000 Mar 24;287(5461):2196-204.
--   http://doi.org/10.1126/science.287.5461.2196
-- 
-- Corrected read consensus sequences are generated using an algorithm derived from FALCON-sense:
--   Chin CS, et al.
--   Phased diploid genome assembly with single-molecule real-time sequencing.
--   Nat Methods. 2016 Dec;13(12):1050-1054.
--   http://doi.org/10.1038/nmeth.4035
-- 
-- Contig consensus sequences are generated using an algorithm derived from pbdagcon:
--   Chin CS, et al.
--   Nonhybrid, finished microbial genome assemblies from long-read SMRT sequencing data.
--   Nat Methods. 2013 Jun;10(6):563-9
--   http://doi.org/10.1038/nmeth.2474
-- 
-- CONFIGURE CANU
--
-- Detected Java(TM) Runtime Environment '1.8.0_112' (from '/n/app/java/jdk-1.8u112/bin/java').
-- Detected gnuplot version '4.6 patchlevel 2' (from 'gnuplot') and image format 'png'.
-- Detected 28 CPUs and 252 gigabytes of memory.
-- Detected Slurm with 'sinfo' binary in /usr/bin/sinfo.
-- Grid engine disabled per useGrid=false option.
--
--                            (tag)Concurrency
--                     (tag)Threads          |
--            (tag)Memory         |          |
--        (tag)         |         |          |     total usage     algorithm
--        -------  ------  --------   --------  -----------------  -----------------------------
-- Local: meryl      8 GB    4 CPUs x   1 job      8 GB    4 CPUs  (k-mer counting)
-- Local: cormhap    6 GB   14 CPUs x   2 jobs    12 GB   28 CPUs  (overlap detection with mhap)
-- Local: obtovl     4 GB    7 CPUs x   4 jobs    16 GB   28 CPUs  (overlap detection)
-- Local: utgovl     4 GB    7 CPUs x   4 jobs    16 GB   28 CPUs  (overlap detection)
-- Local: ovb        4 GB    1 CPU  x  28 jobs   112 GB   28 CPUs  (overlap store bucketizer)
-- Local: ovs        8 GB    1 CPU  x  28 jobs   224 GB   28 CPUs  (overlap store sorting)
-- Local: red        8 GB    4 CPUs x   7 jobs    56 GB   28 CPUs  (read error detection)
-- Local: oea        4 GB    1 CPU  x  28 jobs   112 GB   28 CPUs  (overlap error adjustment)
-- Local: bat       16 GB    4 CPUs x   1 job     16 GB    4 CPUs  (contig construction with bogart)
-- Local: gfa        8 GB    4 CPUs x   1 job      8 GB    4 CPUs  (GFA alignment and processing)
--
-- Found Nanopore uncorrected reads in the input files.
--
-- Generating assembly 'BC05' in '/n/scratch2/dct7/Run11_Canu/BC05_AC347'
--
-- Parameters:
--
--  genomeSize        9000
--
--  Overlap Generation Limits:
--    corOvlErrorRate 0.3200 ( 32.00%)
--    obtOvlErrorRate 0.1440 ( 14.40%)
--    utgOvlErrorRate 0.1440 ( 14.40%)
--
--  Overlap Processing Limits:
--    corErrorRate    0.5000 ( 50.00%)
--    obtErrorRate    0.1440 ( 14.40%)
--    utgErrorRate    0.1440 ( 14.40%)
--    cnsErrorRate    0.1920 ( 19.20%)
--
--
-- BEGIN CORRECTION
--
----------------------------------------
-- Starting command on Fri May  4 09:28:56 2018 with 353849.268 GB free disk space

    cd .
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/sqStoreCreate \
      -o ./BC05.seqStore.BUILDING \
      -minlength 1000 \
      ./BC05.seqStore.ssi \
    > ./BC05.seqStore.BUILDING.err 2>&1

-- Finished on Fri May  4 09:28:57 2018 (one second) with 353849.268 GB free disk space
----------------------------------------
--
-- In sequence store './BC05.seqStore':
--   Found 20964 reads.
--   Found 50496277 bases (5610.69 times coverage).
--
--   Read length histogram (one '*' equals 21.45 reads):
--     1000   1099    991 **********************************************
--     1100   1199   1083 **************************************************
--     1200   1299   1056 *************************************************
--     1300   1399   1064 *************************************************
--     1400   1499   1100 ***************************************************
--     1500   1599   1153 *****************************************************
--     1600   1699   1502 **********************************************************************
--     1700   1799    994 **********************************************
--     1800   1899    875 ****************************************
--     1900   1999    762 ***********************************
--     2000   2099    720 *********************************
--     2100   2199    676 *******************************
--     2200   2299    703 ********************************
--     2300   2399    616 ****************************
--     2400   2499    558 **************************
--     2500   2599    495 ***********************
--     2600   2699    485 **********************
--     2700   2799    470 *********************
--     2800   2899    410 *******************
--     2900   2999    357 ****************
--     3000   3099    413 *******************
--     3100   3199    330 ***************
--     3200   3299    297 *************
--     3300   3399    281 *************
--     3400   3499    265 ************
--     3500   3599    239 ***********
--     3600   3699    249 ***********
--     3700   3799    214 *********
--     3800   3899    190 ********
--     3900   3999    197 *********
--     4000   4099    184 ********
--     4100   4199    154 *******
--     4200   4299    129 ******
--     4300   4399    121 *****
--     4400   4499    134 ******
--     4500   4599     91 ****
--     4600   4699    116 *****
--     4700   4799    102 ****
--     4800   4899     74 ***
--     4900   4999     80 ***
--     5000   5099     60 **
--     5100   5199     68 ***
--     5200   5299     58 **
--     5300   5399     61 **
--     5400   5499     42 *
--     5500   5599     40 *
--     5600   5699     32 *
--     5700   5799     36 *
--     5800   5899     44 **
--     5900   5999     32 *
--     6000   6099     30 *
--     6100   6199     25 *
--     6200   6299     20 
--     6300   6399     25 *
--     6400   6499     19 
--     6500   6599     14 
--     6600   6699     19 
--     6700   6799     19 
--     6800   6899      9 
--     6900   6999     13 
--     7000   7099     12 
--     7100   7199     16 
--     7200   7299      7 
--     7300   7399     15 
--     7400   7499      7 
--     7500   7599     14 
--     7600   7699     10 
--     7700   7799      8 
--     7800   7899      2 
--     7900   7999      7 
--     8000   8099     13 
--     8100   8199      3 
--     8200   8299      5 
--     8300   8399      7 
--     8400   8499      7 
--     8500   8599      5 
--     8600   8699      9 
--     8700   8799     12 
--     8800   8899     16 
--     8900   8999     42 *
--     9000   9099     85 ***
--     9100   9199     57 **
--     9200   9299      4 
--     9300   9399      2 
--     9400   9499      0 
--     9500   9599      0 
--     9600   9699      0 
--     9700   9799      0 
--     9800   9899      1 
--     9900   9999      0 
--    10000  10099      1 
--    10100  10199      0 
--    10200  10299      0 
--    10300  10399      0 
--    10400  10499      0 
--    10500  10599      0 
--    10600  10699      1 
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Fri May  4 09:28:57 2018 with 353849.159 GB free disk space (1 processes; 1 concurrently)

    cd correction/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Fri May  4 09:29:02 2018 (5 seconds) with 353847.912 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  16-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1   7973340 *******************************************************************--> 0.7562 0.1589
--       2-     2   1065611 ********************************************************************** 0.8573 0.2014
--       3-     4    654993 *******************************************                            0.8975 0.2267
--       5-     7    321591 *********************                                                  0.9333 0.2596
--       8-    11    173644 ***********                                                            0.9554 0.2914
--      12-    16    101891 ******                                                                 0.9689 0.3205
--      17-    22     64546 ****                                                                   0.9774 0.3467
--      23-    29     43740 **                                                                     0.9829 0.3703
--      30-    37     30545 **                                                                     0.9868 0.3919
--      38-    46     22275 *                                                                      0.9895 0.4116
--      47-    56     16090 *                                                                      0.9915 0.4297
--      57-    67     12016                                                                        0.9930 0.4457
--      68-    79      9415                                                                        0.9941 0.4602
--      80-    92      7397                                                                        0.9950 0.4737
--      93-   106      6030                                                                        0.9957 0.4863
--     107-   121      4918                                                                        0.9962 0.4980
--     122-   137      4052                                                                        0.9967 0.5091
--     138-   154      3318                                                                        0.9971 0.5195
--     155-   172      2933                                                                        0.9974 0.5291
--     173-   191      2389                                                                        0.9976 0.5385
--     192-   211      1888                                                                        0.9979 0.5471
--     212-   232      1663                                                                        0.9980 0.5545
--     233-   254      1315                                                                        0.9982 0.5618
--     255-   277      1153                                                                        0.9983 0.5682
--     278-   301       969                                                                        0.9984 0.5743
--     302-   326       866                                                                        0.9985 0.5798
--     327-   352       799                                                                        0.9986 0.5852
--     353-   379       587                                                                        0.9987 0.5907
--     380-   407       537                                                                        0.9987 0.5949
--     408-   436       464                                                                        0.9988 0.5991
--     437-   466       383                                                                        0.9988 0.6029
--     467-   497       328                                                                        0.9989 0.6063
--     498-   529       318                                                                        0.9989 0.6095
--     530-   562       275                                                                        0.9989 0.6127
--     563-   596       238                                                                        0.9990 0.6157
--     597-   631       214                                                                        0.9990 0.6184
--     632-   667       207                                                                        0.9990 0.6211
--     668-   704       230                                                                        0.9990 0.6238
--     705-   742       179                                                                        0.9990 0.6269
--     743-   781       198                                                                        0.9991 0.6295
--     782-   821       190                                                                        0.9991 0.6325
--
--       17041 (max occurrences)
--    42208477 (total mers, non-unique)
--     2569947 (distinct mers, non-unique)
--     7973340 (unique mers)
-- For mhap overlapping, the threshold 0.000005000000000 is too low, resetting to 0.000279483702234 to capture 5.000000 * 5610 coverage.
-- For mhap overlapping, set repeat k-mer threshold to 28050.
--
-- Found 50181817 16-mers; 10543287 distinct and 7973340 unique.  Largest count 17041.
--
-- OVERLAPPER (mhap) (correction)
--
-- Set corMhapSensitivity=low based on read coverage of 5610.
--
-- PARAMETERS: hashes=256, minMatches=3, threshold=0.85
--
-- Given 6 GB, can fit 18000 reads per block.
-- For 3 blocks, set stride to 2 blocks.
-- Logging partitioning to 'correction/1-overlapper/partitioning.log'.
-- Configured 2 mhap precompute jobs.
-- Configured 2 mhap overlap jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cormhap' concurrent execution on Fri May  4 09:29:02 2018 with 353847.912 GB free disk space (2 processes; 2 concurrently)

    cd correction/1-overlapper
    ./precompute.sh 1 > ./precompute.000001.out 2>&1
    ./precompute.sh 2 > ./precompute.000002.out 2>&1

-- Finished on Fri May  4 09:29:46 2018 (44 seconds) with 353837.516 GB free disk space
----------------------------------------
-- All 2 mhap precompute jobs finished successfully.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cormhap' concurrent execution on Fri May  4 09:29:46 2018 with 353837.516 GB free disk space (2 processes; 2 concurrently)

    cd correction/1-overlapper
    ./mhap.sh 1 > ./mhap.000001.out 2>&1
    ./mhap.sh 2 > ./mhap.000002.out 2>&1

-- Finished on Fri May  4 09:46:30 2018 (1004 seconds) with 353699.428 GB free disk space
----------------------------------------
-- Found 2 mhap overlap output files.
----------------------------------------
-- Starting command on Fri May  4 09:46:30 2018 with 353699.428 GB free disk space

    cd correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC05.seqStore \
     -M 4-8 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC05.ovlStore.config \
     > ./BC05.ovlStore.config.txt \
    2> ./BC05.ovlStore.config.err

-- Finished on Fri May  4 09:46:30 2018 (in the blink of an eye) with 353699.428 GB free disk space
----------------------------------------
--
-- Creating overlap store correction/BC05.ovlStore using:
--      1 bucket
--      1 slice
--        using at most 7 GB memory each
----------------------------------------
-- Starting command on Fri May  4 09:46:30 2018 with 353699.428 GB free disk space

    cd correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreBuild \
     -O  ./BC05.ovlStore.BUILDING \
     -S ../BC05.seqStore \
     -C  ./BC05.ovlStore.config \
     > ./BC05.ovlStore.err 2>&1

-- Finished on Fri May  4 09:48:13 2018 (103 seconds) with 353690.837 GB free disk space
----------------------------------------
-- Checking store.
----------------------------------------
-- Starting command on Fri May  4 09:48:13 2018 with 353690.837 GB free disk space

    cd correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC05.seqStore \
     -O  ./BC05.ovlStore \
     -counts \
     > ./BC05.ovlStore/counts.dat 2> ./BC05.ovlStore/counts.err

-- Finished on Fri May  4 09:48:13 2018 (in the blink of an eye) with 353690.837 GB free disk space
----------------------------------------
--
-- Overlap store 'correction/BC05.ovlStore' successfully constructed.
-- Found 237302526 overlaps for 20964 reads; 0 reads have no overlaps.
--
--
-- Purged 2.315 GB in 7 overlap output files.
-- Set corMinCoverage=4 based on read coverage of 5610.
-- Global filter scores will be estimated.
-- Computing correction layouts.
----------------------------------------
-- Starting command on Fri May  4 09:48:13 2018 with 353690.837 GB free disk space

    cd correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/generateCorrectionLayouts \
      -S ../BC05.seqStore \
      -O  ./BC05.ovlStore \
      -C  ./BC05.corStore.WORKING \
      -eC 80 \
    > ./BC05.corStore.err 2>&1

-- Finished on Fri May  4 09:48:27 2018 (14 seconds) with 353691.037 GB free disk space
----------------------------------------
-- Computing correction layouts.
----------------------------------------
-- Starting command on Fri May  4 09:48:27 2018 with 353691.037 GB free disk space

    cd correction/2-correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/filterCorrectionLayouts \
      -S  ../../BC05.seqStore \
      -C     ../BC05.corStore \
      -R      ./BC05.readsToCorrect.WORKING \
      -cc 4 \
      -cl 1000 \
      -g  9000 \
      -c  40 \
    > ./BC05.readsToCorrect.err 2>&1

-- Finished on Fri May  4 09:48:28 2018 (one second) with 353691.037 GB free disk space
----------------------------------------
--                             original      original
--                            raw reads     raw reads
--   category                w/overlaps  w/o/overlaps
--   -------------------- ------------- -------------
--   Number of Reads              16280          4684
--   Number of Bases           40684218       9812059
--   Coverage                  4520.469      1090.229
--   Median                        2057          1778
--   Mean                          2499          2094
--   N50                           2790          2257
--   Minimum                       1000          1000
--   Maximum                      10682          9898
--   
--                                        --------corrected---------  ----------rescued----------
--                             evidence                     expected                     expected
--   category                     reads            raw     corrected            raw     corrected
--   -------------------- -------------  ------------- -------------  ------------- -------------
--   Number of Reads               1232             40            40          11694         11694
--   Number of Bases            5429064         366099        365731       28014337      26162806
--   Coverage                   603.229         40.678        40.637       3112.704      2906.978
--   Median                        3394           9146          9136           2058          1887
--   Mean                          4406           9152          9143           2395          2237
--   N50                           7349           9149          9136           2650          2454
--   Minimum                       1007           9104          9103           1005          1001
--   Maximum                      10682           9271          9258           7582          7532
--   
--                        --------uncorrected--------
--                                           expected
--   category                       raw     corrected
--   -------------------- ------------- -------------
--   Number of Reads               9230          9230
--   Number of Bases           22115841       2732100
--   Coverage                  2457.316       303.567
--   Median                        1892             0
--   Mean                          2396           296
--   N50                           2669          7584
--   Minimum                       1000             0
--   Maximum                      10682          9288
--   
--   Maximum Memory           628221104
--
--                            (tag)Concurrency
--                     (tag)Threads          |
--            (tag)Memory         |          |
--        (tag)         |         |          |     total usage     algorithm
--        -------  ------  --------   --------  -----------------  -----------------------------
-- Local: cor        2 GB    4 CPUs x   7 jobs    14 GB   28 CPUs  (read correction)
--
-- Using overlaps no worse than 0.5 fraction error for correcting reads (from corErrorRate parameter).
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cor' concurrent execution on Fri May  4 09:48:28 2018 with 353691.037 GB free disk space (5 processes; 7 concurrently)

    cd correction/2-correction
    ./correctReads.sh 1 > ./correctReads.000001.out 2>&1
    ./correctReads.sh 2 > ./correctReads.000002.out 2>&1
    ./correctReads.sh 3 > ./correctReads.000003.out 2>&1
    ./correctReads.sh 4 > ./correctReads.000004.out 2>&1
    ./correctReads.sh 5 > ./correctReads.000005.out 2>&1

-- Finished on Fri May  4 09:51:02 2018 (154 seconds) with 353669.493 GB free disk space
----------------------------------------
-- Found 5 read correction output files.
-- Found 5 read correction output files.
--
-- Loading corrected reads into corStore and seqStore.
----------------------------------------
-- Starting command on Fri May  4 09:51:02 2018 with 353669.493 GB free disk space

    cd correction
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/loadCorrectedReads \
      -S ../BC05.seqStore \
      -C ./BC05.corStore \
      -L ./2-correction/corjob.files \
    >  ./BC05.loadCorrectedReads.log \
    2> ./BC05.loadCorrectedReads.err

-- Finished on Fri May  4 09:51:02 2018 (lickety-split) with 353669.493 GB free disk space
----------------------------------------
--
-- In sequence store './BC05.seqStore':
--   Found 11730 reads.
--   Found 25808822 bases (2867.64 times coverage).
--
--   Read length histogram (one '*' equals 12.07 reads):
--      500    599      3 
--      600    699      3 
--      700    799     10 
--      800    899     50 ****
--      900    999    231 *******************
--     1000   1099    620 ***************************************************
--     1100   1199    714 ***********************************************************
--     1200   1299    667 *******************************************************
--     1300   1399    681 ********************************************************
--     1400   1499    650 *****************************************************
--     1500   1599    710 **********************************************************
--     1600   1699    845 **********************************************************************
--     1700   1799    507 ******************************************
--     1800   1899    481 ***************************************
--     1900   1999    417 **********************************
--     2000   2099    394 ********************************
--     2100   2199    378 *******************************
--     2200   2299    348 ****************************
--     2300   2399    319 **************************
--     2400   2499    295 ************************
--     2500   2599    291 ************************
--     2600   2699    268 **********************
--     2700   2799    237 *******************
--     2800   2899    194 ****************
--     2900   2999    198 ****************
--     3000   3099    184 ***************
--     3100   3199    166 *************
--     3200   3299    135 ***********
--     3300   3399    125 **********
--     3400   3499    125 **********
--     3500   3599    123 **********
--     3600   3699    125 **********
--     3700   3799     92 *******
--     3800   3899    109 *********
--     3900   3999    106 ********
--     4000   4099     78 ******
--     4100   4199     58 ****
--     4200   4299     70 *****
--     4300   4399     73 ******
--     4400   4499     66 *****
--     4500   4599     51 ****
--     4600   4699     47 ***
--     4700   4799     41 ***
--     4800   4899     39 ***
--     4900   4999     37 ***
--     5000   5099     36 **
--     5100   5199     34 **
--     5200   5299     33 **
--     5300   5399     26 **
--     5400   5499     26 **
--     5500   5599     14 *
--     5600   5699     14 *
--     5700   5799     22 *
--     5800   5899     20 *
--     5900   5999     15 *
--     6000   6099     10 
--     6100   6199     12 
--     6200   6299      8 
--     6300   6399      7 
--     6400   6499      5 
--     6500   6599      9 
--     6600   6699      8 
--     6700   6799      9 
--     6800   6899      4 
--     6900   6999      2 
--     7000   7099      4 
--     7100   7199      5 
--     7200   7299      0 
--     7300   7399      4 
--     7400   7499      2 
--     7500   7599      0 
--     7600   7699      0 
--     7700   7799      0 
--     7800   7899      0 
--     7900   7999      0 
--     8000   8099      0 
--     8100   8199      0 
--     8200   8299      0 
--     8300   8399      0 
--     8400   8499      0 
--     8500   8599      0 
--     8600   8699      0 
--     8700   8799      0 
--     8800   8899      0 
--     8900   8999      2 
--     9000   9099      9 
--     9100   9199     29 **
--
-- Purging correctReads output after loading into stores.
-- Purged 5 .cns outputs.
-- Purged 10 .out job log outputs.
--
-- Purging overlaps used for correction.
--
--
-- BEGIN TRIMMING
--
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Fri May  4 09:51:03 2018 with 353669.493 GB free disk space (1 processes; 1 concurrently)

    cd trimming/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Fri May  4 09:51:05 2018 (2 seconds) with 353672.695 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  22-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1   1067203 *******************************************************************--> 0.7350 0.0417
--       2-     2    140595 ********************************************************************** 0.8318 0.0527
--       3-     4     92910 **********************************************                         0.8726 0.0597
--       5-     7     48400 ************************                                               0.9104 0.0691
--       8-    11     28026 *************                                                          0.9355 0.0788
--      12-    16     19119 *********                                                              0.9515 0.0882
--      17-    22     11121 *****                                                                  0.9632 0.0980
--      23-    29      7446 ***                                                                    0.9702 0.1060
--      30-    37      5267 **                                                                     0.9749 0.1133
--      38-    46      3714 *                                                                      0.9784 0.1200
--      47-    56      2973 *                                                                      0.9808 0.1257
--      57-    67      2455 *                                                                      0.9828 0.1316
--      68-    79      1955                                                                        0.9844 0.1375
--      80-    92      1603                                                                        0.9857 0.1431
--      93-   106      1360                                                                        0.9868 0.1484
--     107-   121      1001                                                                        0.9877 0.1536
--     122-   137       895                                                                        0.9884 0.1581
--     138-   154       766                                                                        0.9890 0.1626
--     155-   172       692                                                                        0.9895 0.1669
--     173-   191       538                                                                        0.9900 0.1713
--     192-   211       420                                                                        0.9904 0.1752
--     212-   232       411                                                                        0.9907 0.1783
--     233-   254       330                                                                        0.9909 0.1819
--     255-   277       297                                                                        0.9912 0.1850
--     278-   301       206                                                                        0.9914 0.1882
--     302-   326       192                                                                        0.9915 0.1904
--     327-   352       205                                                                        0.9916 0.1928
--     353-   379       215                                                                        0.9918 0.1956
--     380-   407       172                                                                        0.9919 0.1986
--     408-   436       265                                                                        0.9921 0.2013
--     437-   466       188                                                                        0.9922 0.2056
--     467-   497       104                                                                        0.9924 0.2089
--     498-   529       134                                                                        0.9924 0.2109
--     530-   562       153                                                                        0.9925 0.2136
--     563-   596       130                                                                        0.9926 0.2169
--     597-   631       201                                                                        0.9927 0.2199
--     632-   667       132                                                                        0.9929 0.2247
--     668-   704       162                                                                        0.9929 0.2279
--     705-   742       153                                                                        0.9931 0.2324
--     743-   781       180                                                                        0.9932 0.2367
--     782-   821       125                                                                        0.9933 0.2420
--
--        9867 (max occurrences)
--    24495289 (total mers, non-unique)
--      384831 (distinct mers, non-unique)
--     1067203 (unique mers)
----------------------------------------
-- Starting command on Fri May  4 09:51:05 2018 with 353672.695 GB free disk space

    cd trimming/0-mercounts
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/meryl \
      -Dt \
      -n 4001 \
      -s ./BC05.ms22 \
    > ./BC05.ms22.frequentMers.fasta \
    2> ./BC05.ms22.frequentMers.fasta.err

-- Finished on Fri May  4 09:51:05 2018 (in the blink of an eye) with 353672.695 GB free disk space
----------------------------------------
-- Reset obtOvlMerThreshold from auto to 4001.
--
-- Found 25562492 22-mers; 1452034 distinct and 1067203 unique.  Largest count 9867.
--
-- OVERLAPPER (normal) (trimming) erate=0.144
--
----------------------------------------
-- Starting command on Fri May  4 09:51:05 2018 with 353672.695 GB free disk space

    cd trimming/1-overlapper
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/overlapInCorePartition \
     -S  ../../BC05.seqStore \
     -hl 85312000 \
     -rl 1000000000 \
     -ol 500 \
     -o  ./BC05.partition \
    > ./BC05.partition.err 2>&1

-- Finished on Fri May  4 09:51:05 2018 (fast as lightning) with 353672.695 GB free disk space
----------------------------------------
--
-- Configured 1 overlapInCore jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'obtovl' concurrent execution on Fri May  4 09:51:05 2018 with 353672.695 GB free disk space (1 processes; 4 concurrently)

    cd trimming/1-overlapper
    ./overlap.sh 1 > ./overlap.000001.out 2>&1

-- Finished on Fri May  4 10:43:39 2018 (3154 seconds) with 353476.904 GB free disk space
----------------------------------------
-- Found 1 overlapInCore output files.
--
-- overlapInCore compute 'trimming/1-overlapper':
--   kmer hits
--     with no overlap         29550243    29550243 +- 0
--     with an overlap         24131068    24131068 +- 0
--
--   overlaps                  24127048    24127048 +- 0
--     contained                      0           0 +- 0
--     dovetail                       0           0 +- 0
--
--   overlaps rejected
--     multiple per pair              0           0 +- 0
--     bad short window               0           0 +- 0
--     bad long window                0           0 +- 0
----------------------------------------
-- Starting command on Fri May  4 10:43:39 2018 with 353476.904 GB free disk space

    cd trimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC05.seqStore \
     -M 7 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC05.ovlStore.config \
     > ./BC05.ovlStore.config.txt \
    2> ./BC05.ovlStore.config.err

-- Finished on Fri May  4 10:43:39 2018 (like a bat out of hell) with 353476.904 GB free disk space
----------------------------------------
--
-- Creating overlap store trimming/BC05.ovlStore using:
--      1 bucket
--      1 slice
--        using at most 2 GB memory each
----------------------------------------
-- Starting command on Fri May  4 10:43:39 2018 with 353476.904 GB free disk space

    cd trimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreBuild \
     -O  ./BC05.ovlStore.BUILDING \
     -S ../BC05.seqStore \
     -C  ./BC05.ovlStore.config \
     > ./BC05.ovlStore.err 2>&1

-- Finished on Fri May  4 10:43:54 2018 (15 seconds) with 353536.672 GB free disk space
----------------------------------------
-- Checking store.
----------------------------------------
-- Starting command on Fri May  4 10:43:54 2018 with 353536.672 GB free disk space

    cd trimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC05.seqStore \
     -O  ./BC05.ovlStore \
     -counts \
     > ./BC05.ovlStore/counts.dat 2> ./BC05.ovlStore/counts.err

-- Finished on Fri May  4 10:43:54 2018 (fuirously fast) with 353536.672 GB free disk space
----------------------------------------
--
-- Overlap store 'trimming/BC05.ovlStore' successfully constructed.
-- Found 48262136 overlaps for 11729 reads; 9235 reads have no overlaps.
--
--
-- Purged 0.406 GB in 3 overlap output files.
----------------------------------------
-- Starting command on Fri May  4 10:43:54 2018 with 353536.672 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/trimReads \
      -S  ../../BC05.seqStore \
      -O  ../BC05.ovlStore \
      -Co ./BC05.1.trimReads.clear \
      -e  0.144 \
      -minlength 1000 \
      -ol 1 \
      -oc 1 \
      -o  ./BC05.1.trimReads \
    >     ./BC05.1.trimReads.err 2>&1

-- Finished on Fri May  4 10:44:05 2018 (11 seconds) with 353536.133 GB free disk space
----------------------------------------
--  PARAMETERS:
--  ----------
--     1000    (reads trimmed below this many bases are deleted)
--   0.1440    (use overlaps at or below this fraction error)
--        1    (break region if overlap is less than this long, for 'largest covered' algorithm)
--        1    (break region if overlap coverage is less than this many read, for 'largest covered' algorithm)
--  
--  INPUT READS:
--  -----------
--   20964 reads     25808822 bases (reads processed)
--       0 reads            0 bases (reads not processed, previously deleted)
--       0 reads            0 bases (reads not processed, in a library where trimming isn't allowed)
--  
--  OUTPUT READS:
--  ------------
--    5890 reads     13103767 bases (trimmed reads output)
--    5439 reads     12090875 bases (reads with no change, kept as is)
--    9235 reads          993 bases (reads with no overlaps, deleted)
--     400 reads       387041 bases (reads with short trimmed length, deleted)
--  
--  TRIMMING DETAILS:
--  ----------------
--    3483 reads       132577 bases (bases trimmed from the 5' end of a read)
--    3352 reads        93569 bases (bases trimmed from the 3' end of a read)
----------------------------------------
-- Starting command on Fri May  4 10:44:05 2018 with 353536.133 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/splitReads \
      -S  ../../BC05.seqStore \
      -O  ../BC05.ovlStore \
      -Ci ./BC05.1.trimReads.clear \
      -Co ./BC05.2.splitReads.clear \
      -e  0.144 \
      -minlength 1000 \
      -o  ./BC05.2.splitReads \
    >     ./BC05.2.splitReads.err 2>&1

-- Finished on Fri May  4 10:44:22 2018 (17 seconds) with 353534.199 GB free disk space
----------------------------------------
--  PARAMETERS:
--  ----------
--     1000    (reads trimmed below this many bases are deleted)
--   0.1440    (use overlaps at or below this fraction error)
--  INPUT READS:
--  -----------
--   11329 reads     25420788 bases (reads processed)
--    9635 reads       388034 bases (reads not processed, previously deleted)
--       0 reads            0 bases (reads not processed, in a library where trimming isn't allowed)
--  
--  PROCESSED:
--  --------
--       0 reads            0 bases (no overlaps)
--       0 reads            0 bases (no coverage after adjusting for trimming done already)
--       0 reads            0 bases (processed for chimera)
--       0 reads            0 bases (processed for spur)
--   11329 reads     25420788 bases (processed for subreads)
--  
--  READS WITH SIGNALS:
--  ------------------
--       0 reads            0 signals (number of 5' spur signal)
--       0 reads            0 signals (number of 3' spur signal)
--       0 reads            0 signals (number of chimera signal)
--       0 reads            0 signals (number of subread signal)
--  
--  SIGNALS:
--  -------
--       0 reads            0 bases (size of 5' spur signal)
--       0 reads            0 bases (size of 3' spur signal)
--       0 reads            0 bases (size of chimera signal)
--       0 reads            0 bases (size of subread signal)
--  
--  TRIMMING:
--  --------
--       0 reads            0 bases (trimmed from the 5' end of the read)
--       0 reads            0 bases (trimmed from the 3' end of the read)
----------------------------------------
-- Starting command on Fri May  4 10:44:22 2018 with 353534.199 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/loadTrimmedReads \
      -S ../../BC05.seqStore \
      -c ./BC05.2.splitReads.clear \
    > ./BC05.loadtrimmedReads.err 2>&1

-- Finished on Fri May  4 10:44:22 2018 (like a bat out of hell) with 353534.199 GB free disk space
----------------------------------------
--
-- In sequence store './BC05.seqStore':
--   Found 11329 reads.
--   Found 25194642 bases (2799.4 times coverage).
--
--   Read length histogram (one '*' equals 11.6 reads):
--     1000   1099    652 ********************************************************
--     1100   1199    722 **************************************************************
--     1200   1299    655 ********************************************************
--     1300   1399    676 **********************************************************
--     1400   1499    648 *******************************************************
--     1500   1599    735 ***************************************************************
--     1600   1699    812 **********************************************************************
--     1700   1799    505 *******************************************
--     1800   1899    468 ****************************************
--     1900   1999    407 ***********************************
--     2000   2099    383 *********************************
--     2100   2199    376 ********************************
--     2200   2299    338 *****************************
--     2300   2399    307 **************************
--     2400   2499    303 **************************
--     2500   2599    286 ************************
--     2600   2699    264 **********************
--     2700   2799    232 ********************
--     2800   2899    182 ***************
--     2900   2999    194 ****************
--     3000   3099    185 ***************
--     3100   3199    155 *************
--     3200   3299    135 ***********
--     3300   3399    129 ***********
--     3400   3499    117 **********
--     3500   3599    130 ***********
--     3600   3699    117 **********
--     3700   3799     96 ********
--     3800   3899    113 *********
--     3900   3999    102 ********
--     4000   4099     75 ******
--     4100   4199     57 ****
--     4200   4299     73 ******
--     4300   4399     72 ******
--     4400   4499     56 ****
--     4500   4599     53 ****
--     4600   4699     46 ***
--     4700   4799     37 ***
--     4800   4899     36 ***
--     4900   4999     39 ***
--     5000   5099     37 ***
--     5100   5199     30 **
--     5200   5299     35 ***
--     5300   5399     23 *
--     5400   5499     25 **
--     5500   5599     14 *
--     5600   5699     16 *
--     5700   5799     22 *
--     5800   5899     20 *
--     5900   5999     12 *
--     6000   6099     11 
--     6100   6199     13 *
--     6200   6299      9 
--     6300   6399      7 
--     6400   6499      4 
--     6500   6599      6 
--     6600   6699      9 
--     6700   6799      8 
--     6800   6899      3 
--     6900   6999      3 
--     7000   7099      4 
--     7100   7199      4 
--     7200   7299      0 
--     7300   7399      4 
--     7400   7499      2 
--     7500   7599      0 
--     7600   7699      0 
--     7700   7799      0 
--     7800   7899      0 
--     7900   7999      0 
--     8000   8099      0 
--     8100   8199      0 
--     8200   8299      0 
--     8300   8399      0 
--     8400   8499      0 
--     8500   8599      0 
--     8600   8699      0 
--     8700   8799      0 
--     8800   8899      0 
--     8900   8999      2 
--     9000   9099      9 
--     9100   9199     29 **
--
-- Overlaps used for trimming saved.
--
--
-- BEGIN ASSEMBLY
--
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Fri May  4 10:44:23 2018 with 353534.199 GB free disk space (1 processes; 1 concurrently)

    cd unitigging/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Fri May  4 10:44:25 2018 (2 seconds) with 353534.203 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  22-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1    931689 *******************************************************************--> 0.7169 0.0373
--       2-     2    132442 ********************************************************************** 0.8188 0.0479
--       3-     4     89155 ***********************************************                        0.8626 0.0548
--       5-     7     46588 ************************                                               0.9031 0.0640
--       8-    11     26969 **************                                                         0.9300 0.0736
--      12-    16     18542 *********                                                              0.9472 0.0828
--      17-    22     10680 *****                                                                  0.9599 0.0926
--      23-    29      7212 ***                                                                    0.9674 0.1005
--      30-    37      5155 **                                                                     0.9726 0.1078
--      38-    46      3429 *                                                                      0.9763 0.1144
--      47-    56      2953 *                                                                      0.9788 0.1199
--      57-    67      2390 *                                                                      0.9810 0.1259
--      68-    79      1925 *                                                                      0.9828 0.1317
--      80-    92      1538                                                                        0.9843 0.1374
--      93-   106      1355                                                                        0.9854 0.1426
--     107-   121       903                                                                        0.9865 0.1478
--     122-   137       904                                                                        0.9872 0.1520
--     138-   154       768                                                                        0.9878 0.1566
--     155-   172       648                                                                        0.9884 0.1612
--     173-   191       582                                                                        0.9889 0.1653
--     192-   211       358                                                                        0.9894 0.1696
--     212-   232       385                                                                        0.9896 0.1724
--     233-   254       341                                                                        0.9899 0.1758
--     255-   277       292                                                                        0.9902 0.1792
--     278-   301       207                                                                        0.9904 0.1822
--     302-   326       183                                                                        0.9906 0.1846
--     327-   352       209                                                                        0.9907 0.1869
--     353-   379       217                                                                        0.9909 0.1899
--     380-   407       187                                                                        0.9910 0.1929
--     408-   436       244                                                                        0.9912 0.1958
--     437-   466       167                                                                        0.9914 0.2001
--     467-   497       100                                                                        0.9915 0.2029
--     498-   529       140                                                                        0.9916 0.2049
--     530-   562       162                                                                        0.9917 0.2078
--     563-   596       140                                                                        0.9918 0.2113
--     597-   631       195                                                                        0.9919 0.2148
--     632-   667       126                                                                        0.9921 0.2194
--     668-   704       162                                                                        0.9922 0.2227
--     705-   742       162                                                                        0.9923 0.2271
--     743-   781       176                                                                        0.9924 0.2319
--     782-   821       113                                                                        0.9925 0.2372
--
--        9148 (max occurrences)
--    24025044 (total mers, non-unique)
--      367994 (distinct mers, non-unique)
--      931689 (unique mers)
----------------------------------------
-- Starting command on Fri May  4 10:44:25 2018 with 353534.203 GB free disk space

    cd unitigging/0-mercounts
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/meryl \
      -Dt \
      -n 3963 \
      -s ./BC05.ms22 \
    > ./BC05.ms22.frequentMers.fasta \
    2> ./BC05.ms22.frequentMers.fasta.err

-- Finished on Fri May  4 10:44:25 2018 (in the blink of an eye) with 353534.203 GB free disk space
----------------------------------------
-- Reset utgOvlMerThreshold from auto to 3963.
--
-- Found 24956733 22-mers; 1299683 distinct and 931689 unique.  Largest count 9148.
--
-- OVERLAPPER (normal) (assembly) erate=0.144
--
----------------------------------------
-- Starting command on Fri May  4 10:44:25 2018 with 353534.203 GB free disk space

    cd unitigging/1-overlapper
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/overlapInCorePartition \
     -S  ../../BC05.seqStore \
     -hl 85312000 \
     -rl 1000000000 \
     -ol 500 \
     -o  ./BC05.partition \
    > ./BC05.partition.err 2>&1

-- Finished on Fri May  4 10:44:25 2018 (fast as lightning) with 353534.203 GB free disk space
----------------------------------------
--
-- Configured 1 overlapInCore jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'utgovl' concurrent execution on Fri May  4 10:44:25 2018 with 353534.203 GB free disk space (1 processes; 4 concurrently)

    cd unitigging/1-overlapper
    ./overlap.sh 1 > ./overlap.000001.out 2>&1

-- Finished on Fri May  4 11:41:15 2018 (3410 seconds) with 352758.493 GB free disk space
----------------------------------------
-- Found 1 overlapInCore output files.
--
-- overlapInCore compute 'unitigging/1-overlapper':
--   kmer hits
--     with no overlap         25656002    25656002 +- 0
--     with an overlap         22290800    22290800 +- 0
--
--   overlaps                  22290800    22290800 +- 0
--     contained               10539812    10539812 +- 0
--     dovetail                11750988    11750988 +- 0
--
--   overlaps rejected
--     multiple per pair              0           0 +- 0
--     bad short window               0           0 +- 0
--     bad long window                0           0 +- 0
----------------------------------------
-- Starting command on Fri May  4 11:41:15 2018 with 352758.493 GB free disk space

    cd unitigging
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC05.seqStore \
     -M 2 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC05.ovlStore.config \
     > ./BC05.ovlStore.config.txt \
    2> ./BC05.ovlStore.config.err

-- Finished on Fri May  4 11:41:15 2018 (lickety-split) with 352758.493 GB free disk space
----------------------------------------
--
-- Creating overlap store unitigging/BC05.ovlStore using:
--      1 bucket
--      1 slice
--        using at most 2 GB memory each
----------------------------------------
-- Starting command on Fri May  4 11:41:15 2018 with 352758.493 GB free disk space

    cd unitigging
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreBuild \
     -O  ./BC05.ovlStore.BUILDING \
     -S ../BC05.seqStore \
     -C  ./BC05.ovlStore.config \
     > ./BC05.ovlStore.err 2>&1

-- Finished on Fri May  4 11:41:29 2018 (14 seconds) with 352755.176 GB free disk space
----------------------------------------
-- Checking store.
----------------------------------------
-- Starting command on Fri May  4 11:41:29 2018 with 352755.176 GB free disk space

    cd unitigging
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC05.seqStore \
     -O  ./BC05.ovlStore \
     -counts \
     > ./BC05.ovlStore/counts.dat 2> ./BC05.ovlStore/counts.err

-- Finished on Fri May  4 11:41:29 2018 (fuirously fast) with 352755.176 GB free disk space
----------------------------------------
--
-- Overlap store 'unitigging/BC05.ovlStore' successfully constructed.
-- Found 44581600 overlaps for 11329 reads; 9635 reads have no overlaps.
--
--
-- Purged 0.333 GB in 3 overlap output files.
----------------------------------------
-- Starting command on Fri May  4 11:41:29 2018 with 352755.176 GB free disk space

    cd unitigging
    /home/dct7/canu_v1.7+/canu/Linux-amd64/bin/ovStoreStats \
     -C 2799 \
     -S ../BC05.seqStore \
     -O  ./BC05.ovlStore \
     -o  ./BC05.ovlStore \
     > ./BC05.ovlStore.summary.err 2>&1

-- Finished on Fri May  4 11:41:39 2018 (10 seconds) with 352752.188 GB free disk space
----------------------------------------
--
-- Overlap store 'unitigging/BC05.ovlStore' contains:
--
--   category            reads     %          read length        feature size or coverage  analysis
--   ----------------  -------  -------  ----------------------  ------------------------  --------------------
--   middle-missing          0    0.00        0.00 +- 0.00             0.00 +- 0.00       (bad trimming)
--   middle-hump             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (bad trimming)
--   no-5-prime              3    0.03     1976.00 +- 574.36          29.33 +- 50.81      (bad trimming)
--   no-3-prime              0    0.00        0.00 +- 0.00             0.00 +- 0.00       (bad trimming)
--   
--   low-coverage           36    0.32     2513.67 +- 1228.94        289.33 +- 290.30     (easy to assemble, potential for lower quality consensus)
--   unique               9392   82.90     2155.08 +- 1079.81       2754.25 +- 867.95     (easy to assemble, perfect, yay)
--   repeat-cont             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (potential for consensus errors, no impact on assembly)
--   repeat-dove             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (hard to assemble, likely won't assemble correctly or even at all)
--   
--   span-repeat            17    0.15     2148.41 +- 1035.13       1136.94 +- 330.40     (read spans a large repeat, usually easy to assemble)
--   uniq-repeat-cont     1878   16.58     2556.09 +- 1412.89                             (should be uniquely placed, low potential for consensus errors, no impact on assembly)
--   uniq-repeat-dove        3    0.03     6953.33 +- 2104.10                             (will end contigs, potential to misassemble)
--   uniq-anchor             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (repeat read, with unique section, probable bad read)
--
-- Loading read lengths.
-- Loading number of overlaps per read.
--
-- Configure RED for 8gb memory.
--                   Batches of at most (unlimited) reads.
--                                      500000000 bases.
--                   Expecting evidence of at most 536870912 bases per iteration.
--
--           Total                                               Reads                 Olaps Evidence
--    Job   Memory      Read Range         Reads        Bases   Memory        Olaps   Memory   Memory  (Memory in MB)
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--      1  3870.88         1-20964         11329     25194642   288.69     44581600   510.20  1024.00
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--                                                   25194642              44581600
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'red' concurrent execution on Fri May  4 11:41:40 2018 with 352752.188 GB free disk space (1 processes; 7 concurrently)

    cd unitigging/3-overlapErrorAdjustment
    ./red.sh 1 > ./red.000001.out 2>&1

-- Finished on Fri May  4 11:41:49 2018 (9 seconds) with 352749.58 GB free disk space
----------------------------------------
--
-- Read error detection jobs failed, retry.
--   job 00001.red FAILED.
--
--
-- Running jobs.  Second attempt out of 2.
----------------------------------------
-- Starting 'red' concurrent execution on Fri May  4 11:41:50 2018 with 352749.58 GB free disk space (1 processes; 7 concurrently)

    cd unitigging/3-overlapErrorAdjustment
    ./red.sh 1 > ./red.000001.out 2>&1

-- Finished on Fri May  4 11:41:54 2018 (4 seconds) with 352748.13 GB free disk space
----------------------------------------
--
-- Read error detection jobs failed, tried 2 times, giving up.
--   job 00001.red FAILED.
--

ABORT:
ABORT: Canu snapshot v1.7 +167 changes (r8859 000ddc276b06b3fc62ef23b953c4fee831f3457a)
ABORT: Don't panic, but a mostly harmless error occurred and Canu stopped.
ABORT: Try restarting.  If that doesn't work, ask for help.
ABORT:

Any help would be greatly appreciated.

Thanks, Damien

letunglam1991 commented 6 years ago

you can open job 0001.red to see error. i have same issue. I open error file and see: "ead_Olaps()-- Loading 3518262 overlaps. Failed to open '../bc1109.ovlStore/0001<001>' for reading: Too many open files".

wait answer from experts.

brianwalenz commented 6 years ago

Fixed. See 6bb19fcb1e79ce255fb7aaeed659247ae4dca9ba and 098679d3a315131e04658b0351f9031b52a85d7d.

If you update from github, you'll probably need to start the assembly over. File formats have (again) changed.

damientully commented 6 years ago

Hi Brian,

That fixed seemed to of worked. Thank you. However for one of the samples I appear to be getting a different error relating to bogart failed that appears to be a memory issue?

Restoring modules from user's modules
-- Canu snapshot v1.7 +184 changes (r8876 098679d3a315131e04658b0351f9031b52a85d7d)
--
-- CITATIONS
--
-- Koren S, Walenz BP, Berlin K, Miller JR, Phillippy AM.
-- Canu: scalable and accurate long-read assembly via adaptive k-mer weighting and repeat separation.
-- Genome Res. 2017 May;27(5):722-736.
-- http://doi.org/10.1101/gr.215087.116
-- 
-- Read and contig alignments during correction, consensus and GFA building use:
--   Šošic M, Šikic M.
--   Edlib: a C/C ++ library for fast, exact sequence alignment using edit distance.
--   Bioinformatics. 2017 May 1;33(9):1394-1395.
--   http://doi.org/10.1093/bioinformatics/btw753
-- 
-- Overlaps are generated using:
--   Berlin K, et al.
--   Assembling large genomes with single-molecule sequencing and locality-sensitive hashing.
--   Nat Biotechnol. 2015 Jun;33(6):623-30.
--   http://doi.org/10.1038/nbt.3238
-- 
--   Myers EW, et al.
--   A Whole-Genome Assembly of Drosophila.
--   Science. 2000 Mar 24;287(5461):2196-204.
--   http://doi.org/10.1126/science.287.5461.2196
-- 
-- Corrected read consensus sequences are generated using an algorithm derived from FALCON-sense:
--   Chin CS, et al.
--   Phased diploid genome assembly with single-molecule real-time sequencing.
--   Nat Methods. 2016 Dec;13(12):1050-1054.
--   http://doi.org/10.1038/nmeth.4035
-- 
-- Contig consensus sequences are generated using an algorithm derived from pbdagcon:
--   Chin CS, et al.
--   Nonhybrid, finished microbial genome assemblies from long-read SMRT sequencing data.
--   Nat Methods. 2013 Jun;10(6):563-9
--   http://doi.org/10.1038/nmeth.2474
-- 
-- CONFIGURE CANU
--
-- Detected Java(TM) Runtime Environment '1.8.0_112' (from '/n/app/java/jdk-1.8u112/bin/java').
-- Detected gnuplot version '4.6 patchlevel 2' (from 'gnuplot') and image format 'png'.
-- Detected 32 CPUs and 252 gigabytes of memory.
-- Detected Slurm with 'sinfo' binary in /usr/bin/sinfo.
-- Grid engine disabled per useGrid=false option.
--
--                            (tag)Concurrency
--                     (tag)Threads          |
--            (tag)Memory         |          |
--        (tag)         |         |          |     total usage     algorithm
--        -------  ------  --------   --------  -----------------  -----------------------------
-- Local: meryl      8 GB    4 CPUs x   1 job      8 GB    4 CPUs  (k-mer counting)
-- Local: cormhap    6 GB   16 CPUs x   2 jobs    12 GB   32 CPUs  (overlap detection with mhap)
-- Local: obtovl     4 GB    8 CPUs x   4 jobs    16 GB   32 CPUs  (overlap detection)
-- Local: utgovl     4 GB    8 CPUs x   4 jobs    16 GB   32 CPUs  (overlap detection)
-- Local: ovb        4 GB    1 CPU  x  32 jobs   128 GB   32 CPUs  (overlap store bucketizer)
-- Local: ovs        8 GB    1 CPU  x  31 jobs   248 GB   31 CPUs  (overlap store sorting)
-- Local: red        8 GB    4 CPUs x   8 jobs    64 GB   32 CPUs  (read error detection)
-- Local: oea        4 GB    1 CPU  x  32 jobs   128 GB   32 CPUs  (overlap error adjustment)
-- Local: bat       16 GB    4 CPUs x   1 job     16 GB    4 CPUs  (contig construction with bogart)
-- Local: gfa        8 GB    4 CPUs x   1 job      8 GB    4 CPUs  (GFA alignment and processing)
--
-- Found Nanopore uncorrected reads in the input files.
--
-- Generating assembly 'BC11' in '/n/scratch2/dct7/Run11_Canu/BC11_565'
--
-- Parameters:
--
--  genomeSize        4000
--
--  Overlap Generation Limits:
--    corOvlErrorRate 0.3200 ( 32.00%)
--    obtOvlErrorRate 0.1440 ( 14.40%)
--    utgOvlErrorRate 0.1440 ( 14.40%)
--
--  Overlap Processing Limits:
--    corErrorRate    0.5000 ( 50.00%)
--    obtErrorRate    0.1440 ( 14.40%)
--    utgErrorRate    0.1440 ( 14.40%)
--    cnsErrorRate    0.1920 ( 19.20%)
--
--
-- BEGIN CORRECTION
--
----------------------------------------
-- Starting command on Sat May  5 18:29:22 2018 with 336768.516 GB free disk space

    cd .
    /home/dct7/canu/Linux-amd64/bin/sqStoreCreate \
      -o ./BC11.seqStore.BUILDING \
      -minlength 1000 \
      ./BC11.seqStore.ssi \
    > ./BC11.seqStore.BUILDING.err 2>&1

-- Finished on Sat May  5 18:29:23 2018 (one second) with 336768.516 GB free disk space
----------------------------------------
--
-- In sequence store './BC11.seqStore':
--   Found 37154 reads.
--   Found 108335973 bases (27083.99 times coverage).
--
--   Read length histogram (one '*' equals 224.62 reads):
--     1000   1099   1262 *****
--     1100   1199    884 ***
--     1200   1299    771 ***
--     1300   1399    734 ***
--     1400   1499    716 ***
--     1500   1599    667 **
--     1600   1699    576 **
--     1700   1799    451 **
--     1800   1899    374 *
--     1900   1999    355 *
--     2000   2099    661 **
--     2100   2199   1946 ********
--     2200   2299    526 **
--     2300   2399    269 *
--     2400   2499    223 
--     2500   2599    257 *
--     2600   2699    250 *
--     2700   2799    226 *
--     2800   2899    266 *
--     2900   2999    293 *
--     3000   3099    405 *
--     3100   3199    689 ***
--     3200   3299   1404 ******
--     3300   3399   4855 *********************
--     3400   3499  15724 **********************************************************************
--     3500   3599   1929 ********
--     3600   3699     77 
--     3700   3799     34 
--     3800   3899     43 
--     3900   3999     61 
--     4000   4099     12 
--     4100   4199     12 
--     4200   4299      8 
--     4300   4399     12 
--     4400   4499     14 
--     4500   4599     20 
--     4600   4699      5 
--     4700   4799      7 
--     4800   4899      8 
--     4900   4999      8 
--     5000   5099      1 
--     5100   5199      9 
--     5200   5299      3 
--     5300   5399      3 
--     5400   5499      6 
--     5500   5599      8 
--     5600   5699     10 
--     5700   5799      3 
--     5800   5899      5 
--     5900   5999      4 
--     6000   6099      2 
--     6100   6199      6 
--     6200   6299      1 
--     6300   6399      4 
--     6400   6499      1 
--     6500   6599     10 
--     6600   6699     13 
--     6700   6799     22 
--     6800   6899      6 
--     6900   6999      0 
--     7000   7099      0 
--     7100   7199      0 
--     7200   7299      1 
--     7300   7399      0 
--     7400   7499      1 
--     7500   7599      0 
--     7600   7699      0 
--     7700   7799      0 
--     7800   7899      0 
--     7900   7999      0 
--     8000   8099      0 
--     8100   8199      0 
--     8200   8299      0 
--     8300   8399      0 
--     8400   8499      0 
--     8500   8599      0 
--     8600   8699      0 
--     8700   8799      0 
--     8800   8899      0 
--     8900   8999      0 
--     9000   9099      0 
--     9100   9199      0 
--     9200   9299      0 
--     9300   9399      0 
--     9400   9499      0 
--     9500   9599      0 
--     9600   9699      0 
--     9700   9799      0 
--     9800   9899      1 
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Sat May  5 18:29:23 2018 with 336768.378 GB free disk space (1 processes; 1 concurrently)

    cd correction/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Sat May  5 18:29:31 2018 (8 seconds) with 336767.72 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  16-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1  11167613 *******************************************************************--> 0.7349 0.1036
--       2-     2   1613146 ********************************************************************** 0.8411 0.1336
--       3-     4   1012779 *******************************************                            0.8841 0.1518
--       5-     7    512646 **********************                                                 0.9229 0.1758
--       8-    11    277495 ************                                                           0.9475 0.1995
--      12-    16    166100 *******                                                                0.9625 0.2212
--      17-    22    106537 ****                                                                   0.9721 0.2411
--      23-    29     71621 ***                                                                    0.9785 0.2592
--      30-    37     51060 **                                                                     0.9829 0.2757
--      38-    46     37126 *                                                                      0.9860 0.2911
--      47-    56     29072 *                                                                      0.9884 0.3052
--      57-    67     22773                                                                        0.9902 0.3188
--      68-    79     17694                                                                        0.9917 0.3317
--      80-    92     14451                                                                        0.9928 0.3436
--      93-   106     11747                                                                        0.9938 0.3549
--     107-   121      9881                                                                        0.9945 0.3656
--     122-   137      8691                                                                        0.9952 0.3761
--     138-   154      6985                                                                        0.9957 0.3863
--     155-   172      5830                                                                        0.9962 0.3957
--     173-   191      4949                                                                        0.9966 0.4045
--     192-   211      4301                                                                        0.9969 0.4128
--     212-   232      3822                                                                        0.9972 0.4208
--     233-   254      3399                                                                        0.9974 0.4286
--     255-   277      2941                                                                        0.9976 0.4362
--     278-   301      2617                                                                        0.9978 0.4435
--     302-   326      2444                                                                        0.9980 0.4504
--     327-   352      2170                                                                        0.9981 0.4576
--     353-   379      1915                                                                        0.9983 0.4644
--     380-   407      1693                                                                        0.9984 0.4708
--     408-   436      1437                                                                        0.9985 0.4770
--     437-   466      1218                                                                        0.9986 0.4826
--     467-   497      1236                                                                        0.9987 0.4877
--     498-   529      1138                                                                        0.9988 0.4932
--     530-   562      1035                                                                        0.9989 0.4986
--     563-   596       880                                                                        0.9989 0.5038
--     597-   631       809                                                                        0.9990 0.5086
--     632-   667       756                                                                        0.9990 0.5132
--     668-   704       670                                                                        0.9991 0.5177
--     705-   742       585                                                                        0.9991 0.5220
--     743-   781       548                                                                        0.9992 0.5259
--     782-   821       555                                                                        0.9992 0.5297
--
--       27280 (max occurrences)
--    96611050 (total mers, non-unique)
--     4028327 (distinct mers, non-unique)
--    11167613 (unique mers)
-- For mhap overlapping, the threshold 0.000005000000000 is too low, resetting to 0.000628208757795 to capture 5.000000 * 27083 coverage.
-- For mhap overlapping, set repeat k-mer threshold to 135415.
--
-- Found 107778663 16-mers; 15195940 distinct and 11167613 unique.  Largest count 27280.
--
-- OVERLAPPER (mhap) (correction)
--
-- Set corMhapSensitivity=low based on read coverage of 27083.
--
-- PARAMETERS: hashes=256, minMatches=3, threshold=0.85
--
-- Given 6 GB, can fit 18000 reads per block.
-- For 4 blocks, set stride to 2 blocks.
-- Logging partitioning to 'correction/1-overlapper/partitioning.log'.
-- Configured 3 mhap precompute jobs.
-- Configured 3 mhap overlap jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cormhap' concurrent execution on Sat May  5 18:29:32 2018 with 336767.72 GB free disk space (3 processes; 2 concurrently)

    cd correction/1-overlapper
    ./precompute.sh 1 > ./precompute.000001.out 2>&1
    ./precompute.sh 2 > ./precompute.000002.out 2>&1
    ./precompute.sh 3 > ./precompute.000003.out 2>&1

-- Finished on Sat May  5 18:30:53 2018 (81 seconds) with 336775.846 GB free disk space
----------------------------------------
-- All 3 mhap precompute jobs finished successfully.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cormhap' concurrent execution on Sat May  5 18:30:53 2018 with 336775.846 GB free disk space (3 processes; 2 concurrently)

    cd correction/1-overlapper
    ./mhap.sh 1 > ./mhap.000001.out 2>&1
    ./mhap.sh 2 > ./mhap.000002.out 2>&1
    ./mhap.sh 3 > ./mhap.000003.out 2>&1

-- Finished on Sat May  5 19:39:11 2018 (4098 seconds) with 336655.617 GB free disk space
----------------------------------------
-- Found 3 mhap overlap output files.
----------------------------------------
-- Starting command on Sat May  5 19:39:11 2018 with 336655.617 GB free disk space

    cd correction
    /home/dct7/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC11.seqStore \
     -M 4-8 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC11.ovlStore.config \
     > ./BC11.ovlStore.config.txt \
    2> ./BC11.ovlStore.config.err

-- Finished on Sat May  5 19:39:11 2018 (like a bat out of hell) with 336655.617 GB free disk space
----------------------------------------
--
-- Creating overlap store correction/BC11.ovlStore using:
--      3 buckets
--      4 slices
--        using at most 8 GB memory each
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'ovB' concurrent execution on Sat May  5 19:39:11 2018 with 336655.617 GB free disk space (3 processes; 32 concurrently)

    cd correction/BC11.ovlStore.BUILDING
    ./scripts/1-bucketize.sh 1 > ./logs/1-bucketize.000001.out 2>&1
    ./scripts/1-bucketize.sh 2 > ./logs/1-bucketize.000002.out 2>&1
    ./scripts/1-bucketize.sh 3 > ./logs/1-bucketize.000003.out 2>&1

-- Finished on Sat May  5 19:42:16 2018 (185 seconds) with 336666.835 GB free disk space
----------------------------------------
-- Overlap store bucketizer finished.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'ovS' concurrent execution on Sat May  5 19:42:16 2018 with 336666.835 GB free disk space (4 processes; 31 concurrently)

    cd correction/BC11.ovlStore.BUILDING
    ./scripts/2-sort.sh 1 > ./logs/2-sort.000001.out 2>&1
    ./scripts/2-sort.sh 2 > ./logs/2-sort.000002.out 2>&1
    ./scripts/2-sort.sh 3 > ./logs/2-sort.000003.out 2>&1
    ./scripts/2-sort.sh 4 > ./logs/2-sort.000004.out 2>&1

-- Finished on Sat May  5 19:44:41 2018 (145 seconds) with 336658.011 GB free disk space
----------------------------------------
-- Overlap store sorter finished.
----------------------------------------
-- Starting command on Sat May  5 19:44:41 2018 with 336658.011 GB free disk space

    cd correction
    /home/dct7/canu/Linux-amd64/bin/ovStoreIndexer \
      -O  ./BC11.ovlStore.BUILDING \
      -S ../BC11.seqStore \
      -C  ./BC11.ovlStore.config \
      -delete \
    > ./BC11.ovlStore.BUILDING.index.err 2>&1

-- Finished on Sat May  5 19:44:41 2018 (like a bat out of hell) with 336658.011 GB free disk space
----------------------------------------
FROM: 'correction/BC11.ovlStore.BUILDING'
TO:   'correction/BC11.ovlStore'
-- Checking store.
----------------------------------------
-- Starting command on Sat May  5 19:44:41 2018 with 336658.011 GB free disk space

    cd correction
    /home/dct7/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC11.seqStore \
     -O  ./BC11.ovlStore \
     -counts \
     > ./BC11.ovlStore/counts.dat 2> ./BC11.ovlStore/counts.err

-- Finished on Sat May  5 19:44:41 2018 (lickety-split) with 336658.011 GB free disk space
----------------------------------------
--
-- Overlap store 'correction/BC11.ovlStore' successfully constructed.
-- Found 1182701906 overlaps for 37154 reads; 0 reads have no overlaps.
--
--
-- Purged 9.974 GB in 12 overlap output files.
-- Set corMinCoverage=4 based on read coverage of 27083.
-- Global filter scores will be estimated.
-- Computing correction layouts.
----------------------------------------
-- Starting command on Sat May  5 19:44:41 2018 with 336658.011 GB free disk space

    cd correction
    /home/dct7/canu/Linux-amd64/bin/generateCorrectionLayouts \
      -S ../BC11.seqStore \
      -O  ./BC11.ovlStore \
      -C  ./BC11.corStore.WORKING \
      -eC 80 \
    > ./BC11.corStore.err 2>&1

-- Finished on Sat May  5 19:45:52 2018 (71 seconds) with 336683.103 GB free disk space
----------------------------------------
-- Computing correction layouts.
----------------------------------------
-- Starting command on Sat May  5 19:45:52 2018 with 336683.103 GB free disk space

    cd correction/2-correction
    /home/dct7/canu/Linux-amd64/bin/filterCorrectionLayouts \
      -S  ../../BC11.seqStore \
      -C     ../BC11.corStore \
      -R      ./BC11.readsToCorrect.WORKING \
      -cc 4 \
      -cl 1000 \
      -g  4000 \
      -c  40 \
    > ./BC11.readsToCorrect.err 2>&1

-- Finished on Sat May  5 19:45:53 2018 (one second) with 336683.103 GB free disk space
----------------------------------------
--                             original      original
--                            raw reads     raw reads
--   category                w/overlaps  w/o/overlaps
--   -------------------- ------------- -------------
--   Number of Reads              25063         12091
--   Number of Bases           74401765      33934208
--   Coverage                 18600.441      8483.552
--   Median                        3422          3341
--   Mean                          2968          2806
--   N50                           3441          3384
--   Minimum                       1000          1000
--   Maximum                       9883          4970
--   
--                                        --------corrected---------  ----------rescued----------
--                             evidence                     expected                     expected
--   category                     reads            raw     corrected            raw     corrected
--   -------------------- -------------  ------------- -------------  ------------- -------------
--   Number of Reads                717             24            24          15973         15973
--   Number of Bases            2089487         162848        162653       48398574      44281471
--   Coverage                   522.372         40.712        40.663      12099.644     11070.368
--   Median                        3103           6768          6756           3437          3365
--   Mean                          2914           6785          6777           3030          2772
--   N50                           3381           6769          6767           3449          3425
--   Minimum                       1000           6714          6713           1012          1001
--   Maximum                       9883           7218          7217           6170          5864
--   
--                        --------uncorrected--------
--                                           expected
--   category                       raw     corrected
--   -------------------- ------------- -------------
--   Number of Reads              21157         21157
--   Number of Bases           59774551        828571
--   Coverage                 14943.638       207.143
--   Median                        3360             0
--   Mean                          2825            39
--   N50                           3401          3075
--   Minimum                       1000             0
--   Maximum                       9883          9865
--   
--   Maximum Memory           626939888
--
--                            (tag)Concurrency
--                     (tag)Threads          |
--            (tag)Memory         |          |
--        (tag)         |         |          |     total usage     algorithm
--        -------  ------  --------   --------  -----------------  -----------------------------
-- Local: cor        2 GB    4 CPUs x   8 jobs    16 GB   32 CPUs  (read correction)
--
-- Using overlaps no worse than 0.5 fraction error for correcting reads (from corErrorRate parameter).
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'cor' concurrent execution on Sat May  5 19:45:53 2018 with 336683.103 GB free disk space (8 processes; 8 concurrently)

    cd correction/2-correction
    ./correctReads.sh 1 > ./correctReads.000001.out 2>&1
    ./correctReads.sh 2 > ./correctReads.000002.out 2>&1
    ./correctReads.sh 3 > ./correctReads.000003.out 2>&1
    ./correctReads.sh 4 > ./correctReads.000004.out 2>&1
    ./correctReads.sh 5 > ./correctReads.000005.out 2>&1
    ./correctReads.sh 6 > ./correctReads.000006.out 2>&1
    ./correctReads.sh 7 > ./correctReads.000007.out 2>&1
    ./correctReads.sh 8 > ./correctReads.000008.out 2>&1

-- Finished on Sat May  5 19:48:31 2018 (158 seconds) with 336679.285 GB free disk space
----------------------------------------
-- Found 8 read correction output files.
-- Found 8 read correction output files.
--
-- Loading corrected reads into corStore and seqStore.
----------------------------------------
-- Starting command on Sat May  5 19:48:31 2018 with 336679.285 GB free disk space

    cd correction
    /home/dct7/canu/Linux-amd64/bin/loadCorrectedReads \
      -S ../BC11.seqStore \
      -C ./BC11.corStore \
      -L ./2-correction/corjob.files \
    >  ./BC11.loadCorrectedReads.log \
    2> ./BC11.loadCorrectedReads.err

-- Finished on Sat May  5 19:48:32 2018 (one second) with 336679.285 GB free disk space
----------------------------------------
--
-- In sequence store './BC11.seqStore':
--   Found 15993 reads.
--   Found 43868121 bases (10967.03 times coverage).
--
--   Read length histogram (one '*' equals 90.55 reads):
--      300    399      1 
--      400    499      0 
--      500    599      1 
--      600    699      6 
--      700    799     13 
--      800    899     32 
--      900    999     97 *
--     1000   1099    825 *********
--     1100   1199    411 ****
--     1200   1299    409 ****
--     1300   1399    371 ****
--     1400   1499    440 ****
--     1500   1599    373 ****
--     1600   1699    279 ***
--     1700   1799    245 **
--     1800   1899    192 **
--     1900   1999    196 **
--     2000   2099    457 *****
--     2100   2199   1080 ***********
--     2200   2299    144 *
--     2300   2399    142 *
--     2400   2499    132 *
--     2500   2599    135 *
--     2600   2699    120 *
--     2700   2799    131 *
--     2800   2899    134 *
--     2900   2999    120 *
--     3000   3099    164 *
--     3100   3199    214 **
--     3200   3299    434 ****
--     3300   3399   2160 ***********************
--     3400   3499   6339 **********************************************************************
--     3500   3599     49 
--     3600   3699      9 
--     3700   3799     13 
--     3800   3899     24 
--     3900   3999     17 
--     4000   4099      4 
--     4100   4199      5 
--     4200   4299      5 
--     4300   4399      7 
--     4400   4499      8 
--     4500   4599     11 
--     4600   4699      4 
--     4700   4799      4 
--     4800   4899      1 
--     4900   4999      2 
--     5000   5099      0 
--     5100   5199      1 
--     5200   5299      2 
--     5300   5399      1 
--     5400   5499      2 
--     5500   5599      2 
--     5600   5699      1 
--     5700   5799      0 
--     5800   5899      0 
--     5900   5999      0 
--     6000   6099      0 
--     6100   6199      0 
--     6200   6299      0 
--     6300   6399      0 
--     6400   6499      0 
--     6500   6599      0 
--     6600   6699      0 
--     6700   6799     21 
--     6800   6899      3 
--
-- Purging correctReads output after loading into stores.
-- Purged 8 .cns outputs.
-- Purged 16 .out job log outputs.
--
-- Purging overlaps used for correction.
--
--
-- BEGIN TRIMMING
--
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Sat May  5 19:48:32 2018 with 336679.285 GB free disk space (1 processes; 1 concurrently)

    cd trimming/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Sat May  5 19:48:34 2018 (2 seconds) with 336680.166 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  22-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1   1164966 *******************************************************************--> 0.6963 0.0268
--       2-     2    175737 ********************************************************************** 0.8013 0.0348
--       3-     4    120357 ***********************************************                        0.8469 0.0401
--       5-     7     68436 ***************************                                            0.8909 0.0475
--       8-    11     41006 ****************                                                       0.9220 0.0557
--      12-    16     24220 *********                                                              0.9427 0.0639
--      17-    22     15896 ******                                                                 0.9551 0.0709
--      23-    29     11867 ****                                                                   0.9638 0.0777
--      30-    37      8666 ***                                                                    0.9705 0.0846
--      38-    46      5808 **                                                                     0.9753 0.0910
--      47-    56      4571 *                                                                      0.9787 0.0964
--      57-    67      3623 *                                                                      0.9813 0.1017
--      68-    79      2882 *                                                                      0.9834 0.1068
--      80-    92      2300                                                                        0.9851 0.1116
--      93-   106      1888                                                                        0.9864 0.1161
--     107-   121      1557                                                                        0.9875 0.1203
--     122-   137      1557                                                                        0.9885 0.1244
--     138-   154      1312                                                                        0.9894 0.1290
--     155-   172      1147                                                                        0.9902 0.1334
--     173-   191      1109                                                                        0.9908 0.1377
--     192-   211       763                                                                        0.9915 0.1423
--     212-   232       690                                                                        0.9920 0.1458
--     233-   254       556                                                                        0.9924 0.1493
--     255-   277       500                                                                        0.9927 0.1523
--     278-   301       452                                                                        0.9930 0.1553
--     302-   326       499                                                                        0.9933 0.1584
--     327-   352       313                                                                        0.9936 0.1620
--     353-   379       308                                                                        0.9937 0.1644
--     380-   407       356                                                                        0.9939 0.1670
--     408-   436       286                                                                        0.9941 0.1703
--     437-   466       286                                                                        0.9943 0.1730
--     467-   497       300                                                                        0.9945 0.1759
--     498-   529       263                                                                        0.9947 0.1793
--     530-   562       288                                                                        0.9948 0.1823
--     563-   596       246                                                                        0.9950 0.1860
--     597-   631       209                                                                        0.9951 0.1892
--     632-   667       181                                                                        0.9953 0.1922
--     668-   704       176                                                                        0.9954 0.1948
--     705-   742       147                                                                        0.9955 0.1976
--     743-   781       160                                                                        0.9956 0.2001
--     782-   821       160                                                                        0.9956 0.2028
--
--       18591 (max occurrences)
--    42367302 (total mers, non-unique)
--      508202 (distinct mers, non-unique)
--     1164966 (unique mers)
----------------------------------------
-- Starting command on Sat May  5 19:48:35 2018 with 336680.166 GB free disk space

    cd trimming/0-mercounts
    /home/dct7/canu/Linux-amd64/bin/meryl \
      -Dt \
      -n 12462 \
      -s ./BC11.ms22 \
    > ./BC11.ms22.frequentMers.fasta \
    2> ./BC11.ms22.frequentMers.fasta.err

-- Finished on Sat May  5 19:48:35 2018 (in the blink of an eye) with 336680.166 GB free disk space
----------------------------------------
-- Reset obtOvlMerThreshold from auto to 12462.
--
-- Found 43532268 22-mers; 1673168 distinct and 1164966 unique.  Largest count 18591.
--
-- OVERLAPPER (normal) (trimming) erate=0.144
--
----------------------------------------
-- Starting command on Sat May  5 19:48:35 2018 with 336680.166 GB free disk space

    cd trimming/1-overlapper
    /home/dct7/canu/Linux-amd64/bin/overlapInCorePartition \
     -S  ../../BC11.seqStore \
     -hl 85312000 \
     -rl 1000000000 \
     -ol 500 \
     -o  ./BC11.partition \
    > ./BC11.partition.err 2>&1

-- Finished on Sat May  5 19:48:35 2018 (like a bat out of hell) with 336680.166 GB free disk space
----------------------------------------
--
-- Configured 1 overlapInCore jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'obtovl' concurrent execution on Sat May  5 19:48:35 2018 with 336680.166 GB free disk space (1 processes; 4 concurrently)

    cd trimming/1-overlapper
    ./overlap.sh 1 > ./overlap.000001.out 2>&1

-- Finished on Sun May  6 08:03:18 2018 (44083 seconds) with 335610.382 GB free disk space
----------------------------------------
-- Found 1 overlapInCore output files.
--
-- overlapInCore compute 'trimming/1-overlapper':
--   kmer hits
--     with no overlap         89078902    89078902 +- 0
--     with an overlap        121397108   121397108 +- 0
--
--   overlaps                 121388258   121388258 +- 0
--     contained                      0           0 +- 0
--     dovetail                       0           0 +- 0
--
--   overlaps rejected
--     multiple per pair              0           0 +- 0
--     bad short window               0           0 +- 0
--     bad long window                0           0 +- 0
----------------------------------------
-- Starting command on Sun May  6 08:03:18 2018 with 335610.382 GB free disk space

    cd trimming
    /home/dct7/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC11.seqStore \
     -M 8 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC11.ovlStore.config \
     > ./BC11.ovlStore.config.txt \
    2> ./BC11.ovlStore.config.err

-- Finished on Sun May  6 08:03:18 2018 (lickety-split) with 335610.382 GB free disk space
----------------------------------------
--
-- Creating overlap store trimming/BC11.ovlStore using:
--      1 bucket
--      1 slice
--        using at most 7 GB memory each
----------------------------------------
-- Starting command on Sun May  6 08:03:18 2018 with 335610.382 GB free disk space

    cd trimming
    /home/dct7/canu/Linux-amd64/bin/ovStoreBuild \
     -O  ./BC11.ovlStore.BUILDING \
     -S ../BC11.seqStore \
     -C  ./BC11.ovlStore.config \
     > ./BC11.ovlStore.err 2>&1

-- Finished on Sun May  6 08:04:33 2018 (75 seconds) with 335599.595 GB free disk space
----------------------------------------
-- Checking store.
----------------------------------------
-- Starting command on Sun May  6 08:04:33 2018 with 335599.595 GB free disk space

    cd trimming
    /home/dct7/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC11.seqStore \
     -O  ./BC11.ovlStore \
     -counts \
     > ./BC11.ovlStore/counts.dat 2> ./BC11.ovlStore/counts.err

-- Finished on Sun May  6 08:04:33 2018 (like a bat out of hell) with 335599.595 GB free disk space
----------------------------------------
--
-- Overlap store 'trimming/BC11.ovlStore' successfully constructed.
-- Found 242794216 overlaps for 15990 reads; 21164 reads have no overlaps.
--
--
-- Purged 1.911 GB in 3 overlap output files.
----------------------------------------
-- Starting command on Sun May  6 08:04:33 2018 with 335599.595 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu/Linux-amd64/bin/trimReads \
      -S  ../../BC11.seqStore \
      -O  ../BC11.ovlStore \
      -Co ./BC11.1.trimReads.clear \
      -e  0.144 \
      -minlength 1000 \
      -ol 1 \
      -oc 1 \
      -o  ./BC11.1.trimReads \
    >     ./BC11.1.trimReads.err 2>&1

-- Finished on Sun May  6 08:05:22 2018 (49 seconds) with 335596.633 GB free disk space
----------------------------------------
--  PARAMETERS:
--  ----------
--     1000    (reads trimmed below this many bases are deleted)
--   0.1440    (use overlaps at or below this fraction error)
--        1    (break region if overlap is less than this long, for 'largest covered' algorithm)
--        1    (break region if overlap coverage is less than this many read, for 'largest covered' algorithm)
--  
--  INPUT READS:
--  -----------
--   37154 reads     43868121 bases (reads processed)
--       0 reads            0 bases (reads not processed, previously deleted)
--       0 reads            0 bases (reads not processed, in a library where trimming isn't allowed)
--  
--  OUTPUT READS:
--  ------------
--    2389 reads      6141070 bases (trimmed reads output)
--   13420 reads     37409827 bases (reads with no change, kept as is)
--   21164 reads         2175 bases (reads with no overlaps, deleted)
--     181 reads       170989 bases (reads with short trimmed length, deleted)
--  
--  TRIMMING DETAILS:
--  ----------------
--    1159 reads        76336 bases (bases trimmed from the 5' end of a read)
--    1317 reads        67724 bases (bases trimmed from the 3' end of a read)
----------------------------------------
-- Starting command on Sun May  6 08:05:22 2018 with 335596.633 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu/Linux-amd64/bin/splitReads \
      -S  ../../BC11.seqStore \
      -O  ../BC11.ovlStore \
      -Ci ./BC11.1.trimReads.clear \
      -Co ./BC11.2.splitReads.clear \
      -e  0.144 \
      -minlength 1000 \
      -o  ./BC11.2.splitReads \
    >     ./BC11.2.splitReads.err 2>&1

-- Finished on Sun May  6 08:06:57 2018 (95 seconds) with 335584.579 GB free disk space
----------------------------------------
--  PARAMETERS:
--  ----------
--     1000    (reads trimmed below this many bases are deleted)
--   0.1440    (use overlaps at or below this fraction error)
--  INPUT READS:
--  -----------
--   15809 reads     43694957 bases (reads processed)
--   21345 reads       173164 bases (reads not processed, previously deleted)
--       0 reads            0 bases (reads not processed, in a library where trimming isn't allowed)
--  
--  PROCESSED:
--  --------
--       0 reads            0 bases (no overlaps)
--       0 reads            0 bases (no coverage after adjusting for trimming done already)
--       0 reads            0 bases (processed for chimera)
--       0 reads            0 bases (processed for spur)
--   15809 reads     43694957 bases (processed for subreads)
--  
--  READS WITH SIGNALS:
--  ------------------
--       0 reads            0 signals (number of 5' spur signal)
--       0 reads            0 signals (number of 3' spur signal)
--       0 reads            0 signals (number of chimera signal)
--       2 reads            2 signals (number of subread signal)
--  
--  SIGNALS:
--  -------
--       0 reads            0 bases (size of 5' spur signal)
--       0 reads            0 bases (size of 3' spur signal)
--       0 reads            0 bases (size of chimera signal)
--       2 reads         1323 bases (size of subread signal)
--  
--  TRIMMING:
--  --------
--       2 reads         2397 bases (trimmed from the 5' end of the read)
--       0 reads            0 bases (trimmed from the 3' end of the read)
----------------------------------------
-- Starting command on Sun May  6 08:06:57 2018 with 335584.579 GB free disk space

    cd trimming/3-overlapbasedtrimming
    /home/dct7/canu/Linux-amd64/bin/loadTrimmedReads \
      -S ../../BC11.seqStore \
      -c ./BC11.2.splitReads.clear \
    > ./BC11.loadtrimmedReads.err 2>&1

-- Finished on Sun May  6 08:06:57 2018 (fuirously fast) with 335584.579 GB free disk space
----------------------------------------
--
-- In sequence store './BC11.seqStore':
--   Found 15808 reads.
--   Found 43547680 bases (10886.92 times coverage).
--
--   Read length histogram (one '*' equals 89.71 reads):
--     1000   1099    823 *********
--     1100   1199    415 ****
--     1200   1299    399 ****
--     1300   1399    383 ****
--     1400   1499    453 *****
--     1500   1599    375 ****
--     1600   1699    285 ***
--     1700   1799    238 **
--     1800   1899    185 **
--     1900   1999    200 **
--     2000   2099    452 *****
--     2100   2199   1072 ***********
--     2200   2299    151 *
--     2300   2399    147 *
--     2400   2499    128 *
--     2500   2599    139 *
--     2600   2699    125 *
--     2700   2799    127 *
--     2800   2899    144 *
--     2900   2999    125 *
--     3000   3099    170 *
--     3100   3199    206 **
--     3200   3299    429 ****
--     3300   3399   2188 ************************
--     3400   3499   6280 **********************************************************************
--     3500   3599     51 
--     3600   3699      5 
--     3700   3799     11 
--     3800   3899     22 
--     3900   3999     16 
--     4000   4099      2 
--     4100   4199      1 
--     4200   4299      3 
--     4300   4399      5 
--     4400   4499      8 
--     4500   4599     11 
--     4600   4699      1 
--     4700   4799      1 
--     4800   4899      0 
--     4900   4999      2 
--     5000   5099      0 
--     5100   5199      0 
--     5200   5299      2 
--     5300   5399      1 
--     5400   5499      1 
--     5500   5599      1 
--     5600   5699      1 
--     5700   5799      0 
--     5800   5899      0 
--     5900   5999      0 
--     6000   6099      0 
--     6100   6199      0 
--     6200   6299      0 
--     6300   6399      0 
--     6400   6499      0 
--     6500   6599      0 
--     6600   6699      0 
--     6700   6799     21 
--     6800   6899      3 
--
-- Overlaps used for trimming saved.
--
--
-- BEGIN ASSEMBLY
--
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'meryl' concurrent execution on Sun May  6 08:06:57 2018 with 335584.579 GB free disk space (1 processes; 1 concurrently)

    cd unitigging/0-mercounts
    ./meryl.sh 1 > ./meryl.000001.out 2>&1

-- Finished on Sun May  6 08:06:59 2018 (2 seconds) with 335584.133 GB free disk space
----------------------------------------
-- Meryl finished successfully.
--
--  22-mers                                                                                           Fraction
--    Occurrences   NumMers                                                                         Unique Total
--       1-     1   1091214 *******************************************************************--> 0.6855 0.0253
--       2-     2    172168 ********************************************************************** 0.7937 0.0332
--       3-     4    118686 ************************************************                       0.8409 0.0384
--       5-     7     67733 ***************************                                            0.8867 0.0459
--       8-    11     40344 ****************                                                       0.9190 0.0540
--      12-    16     23876 *********                                                              0.9403 0.0621
--      17-    22     15706 ******                                                                 0.9531 0.0690
--      23-    29     11807 ****                                                                   0.9623 0.0758
--      30-    37      8511 ***                                                                    0.9692 0.0827
--      38-    46      5753 **                                                                     0.9743 0.0891
--      47-    56      4523 *                                                                      0.9777 0.0945
--      57-    67      3602 *                                                                      0.9805 0.0998
--      68-    79      2835 *                                                                      0.9827 0.1049
--      80-    92      2286                                                                        0.9844 0.1096
--      93-   106      1879                                                                        0.9858 0.1141
--     107-   121      1577                                                                        0.9870 0.1183
--     122-   137      1519                                                                        0.9880 0.1225
--     138-   154      1326                                                                        0.9889 0.1270
--     155-   172      1123                                                                        0.9897 0.1315
--     173-   191      1095                                                                        0.9904 0.1357
--     192-   211       769                                                                        0.9911 0.1403
--     212-   232       689                                                                        0.9916 0.1439
--     233-   254       530                                                                        0.9920 0.1473
--     255-   277       486                                                                        0.9923 0.1503
--     278-   301       476                                                                        0.9926 0.1533
--     302-   326       487                                                                        0.9929 0.1565
--     327-   352       297                                                                        0.9932 0.1600
--     353-   379       315                                                                        0.9934 0.1623
--     380-   407       363                                                                        0.9936 0.1649
--     408-   436       273                                                                        0.9938 0.1682
--     437-   466       291                                                                        0.9940 0.1709
--     467-   497       302                                                                        0.9942 0.1740
--     498-   529       254                                                                        0.9944 0.1774
--     530-   562       291                                                                        0.9946 0.1804
--     563-   596       246                                                                        0.9947 0.1840
--     597-   631       203                                                                        0.9949 0.1873
--     632-   667       181                                                                        0.9950 0.1902
--     668-   704       177                                                                        0.9951 0.1929
--     705-   742       146                                                                        0.9952 0.1957
--     743-   781       159                                                                        0.9953 0.1982
--     782-   821       165                                                                        0.9954 0.2009
--
--       18349 (max occurrences)
--    42124498 (total mers, non-unique)
--      500557 (distinct mers, non-unique)
--     1091214 (unique mers)
----------------------------------------
-- Starting command on Sun May  6 08:06:59 2018 with 335584.133 GB free disk space

    cd unitigging/0-mercounts
    /home/dct7/canu/Linux-amd64/bin/meryl \
      -Dt \
      -n 12385 \
      -s ./BC11.ms22 \
    > ./BC11.ms22.frequentMers.fasta \
    2> ./BC11.ms22.frequentMers.fasta.err

-- Finished on Sun May  6 08:06:59 2018 (in the blink of an eye) with 335584.133 GB free disk space
----------------------------------------
-- Reset utgOvlMerThreshold from auto to 12385.
--
-- Found 43215712 22-mers; 1591771 distinct and 1091214 unique.  Largest count 18349.
--
-- OVERLAPPER (normal) (assembly) erate=0.144
--
----------------------------------------
-- Starting command on Sun May  6 08:06:59 2018 with 335584.133 GB free disk space

    cd unitigging/1-overlapper
    /home/dct7/canu/Linux-amd64/bin/overlapInCorePartition \
     -S  ../../BC11.seqStore \
     -hl 85312000 \
     -rl 1000000000 \
     -ol 500 \
     -o  ./BC11.partition \
    > ./BC11.partition.err 2>&1

-- Finished on Sun May  6 08:06:59 2018 (fast as lightning) with 335584.133 GB free disk space
----------------------------------------
--
-- Configured 1 overlapInCore jobs.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'utgovl' concurrent execution on Sun May  6 08:06:59 2018 with 335584.133 GB free disk space (1 processes; 4 concurrently)

    cd unitigging/1-overlapper
    ./overlap.sh 1 > ./overlap.000001.out 2>&1

-- Finished on Mon May  7 01:47:11 2018 (63612 seconds) with 354954.779 GB free disk space
----------------------------------------
-- Found 1 overlapInCore output files.
--
-- overlapInCore compute 'unitigging/1-overlapper':
--   kmer hits
--     with no overlap        110479702   110479702 +- 0
--     with an overlap         94235889    94235889 +- 0
--
--   overlaps                  94235889    94235889 +- 0
--     contained               39960749    39960749 +- 0
--     dovetail                54275140    54275140 +- 0
--
--   overlaps rejected
--     multiple per pair              0           0 +- 0
--     bad short window               0           0 +- 0
--     bad long window                0           0 +- 0
----------------------------------------
-- Starting command on Mon May  7 01:47:12 2018 with 354954.779 GB free disk space

    cd unitigging
    /home/dct7/canu/Linux-amd64/bin/ovStoreConfig \
     -S ../BC11.seqStore \
     -M 7 \
     -L ./1-overlapper/ovljob.files \
     -create ./BC11.ovlStore.config \
     > ./BC11.ovlStore.config.txt \
    2> ./BC11.ovlStore.config.err

-- Finished on Mon May  7 01:47:12 2018 (fuirously fast) with 354954.779 GB free disk space
----------------------------------------
--
-- Creating overlap store unitigging/BC11.ovlStore using:
--      1 bucket
--      1 slice
--        using at most 5 GB memory each
----------------------------------------
-- Starting command on Mon May  7 01:47:12 2018 with 354954.779 GB free disk space

    cd unitigging
    /home/dct7/canu/Linux-amd64/bin/ovStoreBuild \
     -O  ./BC11.ovlStore.BUILDING \
     -S ../BC11.seqStore \
     -C  ./BC11.ovlStore.config \
     > ./BC11.ovlStore.err 2>&1

-- Finished on Mon May  7 01:48:15 2018 (63 seconds) with 354949.658 GB free disk space
----------------------------------------
-- Checking store.
----------------------------------------
-- Starting command on Mon May  7 01:48:15 2018 with 354949.658 GB free disk space

    cd unitigging
    /home/dct7/canu/Linux-amd64/bin/ovStoreDump \
     -S ../BC11.seqStore \
     -O  ./BC11.ovlStore \
     -counts \
     > ./BC11.ovlStore/counts.dat 2> ./BC11.ovlStore/counts.err

-- Finished on Mon May  7 01:48:16 2018 (one second) with 354949.658 GB free disk space
----------------------------------------
--
-- Overlap store 'unitigging/BC11.ovlStore' successfully constructed.
-- Found 188471778 overlaps for 15808 reads; 21346 reads have no overlaps.
--
--
-- Purged 1.246 GB in 3 overlap output files.
----------------------------------------
-- Starting command on Mon May  7 01:48:16 2018 with 354949.658 GB free disk space

    cd unitigging
    /home/dct7/canu/Linux-amd64/bin/ovStoreStats \
     -C 10886 \
     -S ../BC11.seqStore \
     -O  ./BC11.ovlStore \
     -o  ./BC11.ovlStore \
     > ./BC11.ovlStore.summary.err 2>&1

-- Finished on Mon May  7 01:48:58 2018 (42 seconds) with 354951.011 GB free disk space
----------------------------------------
--
-- Overlap store 'unitigging/BC11.ovlStore' contains:
--
--   category            reads     %          read length        feature size or coverage  analysis
--   ----------------  -------  -------  ----------------------  ------------------------  --------------------
--   middle-missing          0    0.00        0.00 +- 0.00             0.00 +- 0.00       (bad trimming)
--   middle-hump             1    0.01     4458.00 +- 0.00             0.00 +- 0.00       (bad trimming)
--   no-5-prime              2    0.01     3661.00 +- 1159.66        449.50 +- 635.69     (bad trimming)
--   no-3-prime              4    0.03     2333.50 +- 1078.21        147.75 +- 216.09     (bad trimming)
--   
--   low-coverage         1727   10.92     2039.02 +- 410.65        1951.20 +- 515.24     (easy to assemble, potential for lower quality consensus)
--   unique               7111   44.98     2606.38 +- 951.99       11273.95 +- 952.29     (easy to assemble, perfect, yay)
--   repeat-cont             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (potential for consensus errors, no impact on assembly)
--   repeat-dove             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (hard to assemble, likely won't assemble correctly or even at all)
--   
--   span-repeat            44    0.28     3505.23 +- 1343.01       2773.34 +- 1258.21    (read spans a large repeat, usually easy to assemble)
--   uniq-repeat-cont     6907   43.69     3077.35 +- 754.24                              (should be uniquely placed, low potential for consensus errors, no impact on assembly)
--   uniq-repeat-dove       12    0.08     5145.50 +- 944.02                              (will end contigs, potential to misassemble)
--   uniq-anchor             0    0.00        0.00 +- 0.00             0.00 +- 0.00       (repeat read, with unique section, probable bad read)
--
-- Loading read lengths.
-- Loading number of overlaps per read.
--
-- Configure RED for 8gb memory.
--                   Batches of at most (unlimited) reads.
--                                      500000000 bases.
--                   Expecting evidence of at most 536870912 bases per iteration.
--
--           Total                                               Reads                 Olaps Evidence
--    Job   Memory      Read Range         Reads        Bases   Memory        Olaps   Memory   Memory  (Memory in MB)
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--      1  5727.75         1-37154         15808     43547680   498.86    188471778  2156.89  1024.00
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--                                                   43547680             188471778
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'red' concurrent execution on Mon May  7 01:48:59 2018 with 354951.011 GB free disk space (1 processes; 8 concurrently)

    cd unitigging/3-overlapErrorAdjustment
    ./red.sh 1 > ./red.000001.out 2>&1

-- Finished on Mon May  7 04:59:00 2018 (11401 seconds) with 354876.501 GB free disk space
----------------------------------------
-- Found 1 read error detection output files.
--
-- Loading read lengths.
-- Loading number of overlaps per read.
--
-- Configure OEA for 4gb memory.
--                   Batches of at most (unlimited) reads.
--                                      300000000 bases.
--
--           Total                                               Reads                 Olaps  Adjusts
--    Job   Memory      Read Range         Reads        Bases   Memory        Olaps   Memory   Memory  (Memory in MB)
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--      1  4096.23         1-10862          4704     12962153    20.51     55927364  1706.77   320.96
--      2  4096.34     10863-23113          4690     12736452    20.29     55938032  1707.09   320.96
--      3  4096.40     23114-33279          4689     13038102    20.58     55930512  1706.86   320.96
--      4  3012.57     33280-37154          1725      4810973    12.64     20675870   630.98   320.96
--   ---- -------- ------------------- --------- ------------ -------- ------------ -------- --------
--                                                   43547680             188471778
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'oea' concurrent execution on Mon May  7 04:59:00 2018 with 354876.501 GB free disk space (4 processes; 32 concurrently)

    cd unitigging/3-overlapErrorAdjustment
    ./oea.sh 1 > ./oea.000001.out 2>&1
    ./oea.sh 2 > ./oea.000002.out 2>&1
    ./oea.sh 3 > ./oea.000003.out 2>&1
    ./oea.sh 4 > ./oea.000004.out 2>&1

-- Finished on Mon May  7 08:27:28 2018 (12508 seconds) with 354749.002 GB free disk space
----------------------------------------
-- Found 4 overlap error adjustment output files.
----------------------------------------
-- Starting command on Mon May  7 08:27:28 2018 with 354749.002 GB free disk space

    cd unitigging/3-overlapErrorAdjustment
    /home/dct7/canu/Linux-amd64/bin/loadErates \
      -S ../../BC11.seqStore \
      -O ../BC11.ovlStore \
      -L ./oea.files \
    > ./oea.apply.err 2>&1

-- Finished on Mon May  7 08:27:29 2018 (one second) with 354749.002 GB free disk space
----------------------------------------
-- No report available.
--
-- Running jobs.  First attempt out of 2.
----------------------------------------
-- Starting 'bat' concurrent execution on Mon May  7 08:27:29 2018 with 354749.002 GB free disk space (1 processes; 1 concurrently)

    cd unitigging/4-unitigger
    ./unitigger.sh 1 > ./unitigger.000001.out 2>&1

-- Finished on Mon May  7 08:27:29 2018 (fast as lightning) with 354748.974 GB free disk space
----------------------------------------
--
-- Bogart failed, retry
--
--
-- Running jobs.  Second attempt out of 2.
----------------------------------------
-- Starting 'bat' concurrent execution on Mon May  7 08:27:29 2018 with 354748.974 GB free disk space (1 processes; 1 concurrently)

    cd unitigging/4-unitigger
    ./unitigger.sh 1 > ./unitigger.000001.out 2>&1

-- Finished on Mon May  7 08:27:29 2018 (like a bat out of hell) with 354748.974 GB free disk space
----------------------------------------
--
-- Bogart failed, tried 2 times, giving up.
--

ABORT:
ABORT: Canu snapshot v1.7 +184 changes (r8876 098679d3a315131e04658b0351f9031b52a85d7d)
ABORT: Don't panic, but a mostly harmless error occurred and Canu stopped.
ABORT: Try restarting.  If that doesn't work, ask for help.
ABORT:
ABORT: Disk space available:  354748.974 GB
ABORT:
ABORT: Last 50 lines of the relevant log file (unitigging/4-unitigger/unitigger.err):
ABORT:
ABORT:   
ABORT:   Lengths:
ABORT:     Minimum read          0 bases
ABORT:     Minimum overlap       500 bases
ABORT:   
ABORT:   Overlap Error Rates:
ABORT:     Graph                 0.144 (14.400%)
ABORT:     Max                   0.144 (14.400%)
ABORT:   
ABORT:   Deviations:
ABORT:     Graph                 6.000
ABORT:     Bubble                6.000
ABORT:     Repeat                3.000
ABORT:   
ABORT:   Edge Confusion:
ABORT:     Absolute              2100
ABORT:     Percent               200.0000
ABORT:   
ABORT:   Unitig Construction:
ABORT:     Minimum intersection  500 bases
ABORT:     Maxiumum placements   2 positions
ABORT:   
ABORT:   Debugging Enabled:
ABORT:     (none)
ABORT:   
ABORT:   ==> LOADING AND FILTERING OVERLAPS.
ABORT:   
ABORT:   ReadInfo()-- Using 37154 reads, no minimum read length used.
ABORT:   
ABORT:   OverlapCache()-- limited to 16384MB memory (user supplied).
ABORT:   
ABORT:   OverlapCache()--       0MB for read data.
ABORT:   OverlapCache()--       1MB for best edges.
ABORT:   OverlapCache()--       3MB for tigs.
ABORT:   OverlapCache()--       0MB for tigs - read layouts.
ABORT:   OverlapCache()--       1MB for tigs - error profiles.
ABORT:   OverlapCache()--    4096MB for tigs - error profile overlaps.
ABORT:   OverlapCache()--       0MB for other processes.
ABORT:   OverlapCache()-- ---------
ABORT:   OverlapCache()--    4104MB for data structures (sum of above).
ABORT:   OverlapCache()-- ---------
ABORT:   OverlapCache()--       0MB for overlap store structure.
ABORT:   OverlapCache()--   12278MB for overlap data.
ABORT:   OverlapCache()-- ---------
ABORT:   OverlapCache()--   16384MB allowed.
ABORT:   OverlapCache()--
ABORT:   OverlapCache()-- Retain at least 21773 overlaps/read, based on 10886.92x coverage.
ABORT:   OverlapCache()-- Initial guess at 21658 overlaps/read.
ABORT:   OverlapCache()--
ABORT:   OverlapCache()-- Not enough memory to load the minimum number of overlaps; increase -M.
ABORT:
skoren commented 6 years ago

This is related to your high coverage, this looks like an amplicon (also see #876 and #894)? You start with >5000 fold coverage and end up with > 2000 fold corrected reads. Try running with batMemory=200, you should just be able to remove the unitigging/4-unitigger folder and re-start the same command. Alternatively, randomly downsample your dataset to 200-fold coverage.