Diff of the two buildlogs: -- --- b1/build.log 2022-01-10 04:52:48.212696942 +0000 +++ b2/build.log 2022-01-10 04:59:38.035974692 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sat Feb 11 23:13:45 -12 2023 -I: pbuilder-time-stamp: 1676200425 +I: Current time: Mon Jan 10 18:52:57 +14 2022 +I: pbuilder-time-stamp: 1641790377 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -16,8 +16,8 @@ I: copying [./racon_1.4.20-1.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.zUVYYOIB/trustedkeys.kbx': General error -gpgv: Signature made Mon Jan 18 22:04:56 2021 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.Ay7wokxu/trustedkeys.kbx': General error +gpgv: Signature made Wed Jan 20 00:04:56 2021 +14 gpgv: using RSA key 724D609337113C710550D7473C26763F6C67E6E2 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./racon_1.4.20-1.dsc @@ -30,135 +30,169 @@ dpkg-source: info: applying py3 I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2906700/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/D01_modify_environment starting +debug: Running on ionos11-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +Removing 'diversion of /bin/sh to /bin/sh.distrib by dash' +Adding 'diversion of /bin/sh to /bin/sh.distrib by bash' +Removing 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by dash' +Adding 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by bash' +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=16' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="1" [2]="4" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.1.4(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=15' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='027d6cbd9ea846129632366f45c15867' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='2906700' - PS1='# ' - PS2='> ' + INVOCATION_ID=a14eaef596fd41b6b81e18925d497c18 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=3289031 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.CjyAK37vgj/pbuilderrc_59cj --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.CjyAK37vgj/b1 --logfile b1/build.log racon_1.4.20-1.dsc' - SUDO_GID='110' - SUDO_UID='105' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://85.184.249.68:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.CjyAK37vgj/pbuilderrc_f8XX --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.CjyAK37vgj/b2 --logfile b2/build.log racon_1.4.20-1.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://78.137.99.97:3128 I: uname -a - Linux ionos5-amd64 5.14.0-0.bpo.2-amd64 #1 SMP Debian 5.14.9-2~bpo11+1 (2021-10-10) x86_64 GNU/Linux + Linux i-capture-the-hostname 5.10.0-10-amd64 #1 SMP Debian 5.10.84-1 (2021-12-08) x86_64 GNU/Linux I: ls -l /bin total 5476 - -rwxr-xr-x 1 root root 1234376 Aug 4 2021 bash - -rwxr-xr-x 3 root root 38984 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 38984 Jul 20 2020 bzcat - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Jul 20 2020 bzdiff - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4877 Sep 4 2019 bzexe - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Jul 20 2020 bzgrep - -rwxr-xr-x 3 root root 38984 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 18424 Jul 20 2020 bzip2recover - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Jul 20 2020 bzmore - -rwxr-xr-x 1 root root 43936 Sep 23 2020 cat - -rwxr-xr-x 1 root root 72672 Sep 23 2020 chgrp - -rwxr-xr-x 1 root root 64448 Sep 23 2020 chmod - -rwxr-xr-x 1 root root 72672 Sep 23 2020 chown - -rwxr-xr-x 1 root root 151168 Sep 23 2020 cp - -rwxr-xr-x 1 root root 125560 Dec 10 2020 dash - -rwxr-xr-x 1 root root 113664 Sep 23 2020 date - -rwxr-xr-x 1 root root 80968 Sep 23 2020 dd - -rwxr-xr-x 1 root root 93936 Sep 23 2020 df - -rwxr-xr-x 1 root root 147176 Sep 23 2020 dir - -rwxr-xr-x 1 root root 84440 Jul 28 2021 dmesg - lrwxrwxrwx 1 root root 8 Nov 6 2019 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Nov 6 2019 domainname -> hostname - -rwxr-xr-x 1 root root 39712 Sep 23 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 39680 Sep 23 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 69032 Jul 28 2021 findmnt - -rwsr-xr-x 1 root root 34896 Feb 26 2021 fusermount - -rwxr-xr-x 1 root root 203072 Nov 9 2020 grep - -rwxr-xr-x 2 root root 2346 Mar 2 2021 gunzip - -rwxr-xr-x 1 root root 6376 Mar 2 2021 gzexe - -rwxr-xr-x 1 root root 98048 Mar 2 2021 gzip - -rwxr-xr-x 1 root root 22600 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 72840 Sep 23 2020 ln - -rwxr-xr-x 1 root root 56952 Feb 7 2020 login - -rwxr-xr-x 1 root root 147176 Sep 23 2020 ls - -rwxr-xr-x 1 root root 149736 Jul 28 2021 lsblk - -rwxr-xr-x 1 root root 85184 Sep 23 2020 mkdir - -rwxr-xr-x 1 root root 76896 Sep 23 2020 mknod - -rwxr-xr-x 1 root root 48064 Sep 23 2020 mktemp - -rwxr-xr-x 1 root root 59632 Jul 28 2021 more - -rwsr-xr-x 1 root root 55528 Jul 28 2021 mount - -rwxr-xr-x 1 root root 18664 Jul 28 2021 mountpoint - -rwxr-xr-x 1 root root 147080 Sep 23 2020 mv - lrwxrwxrwx 1 root root 8 Nov 6 2019 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 18 2021 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 43872 Sep 23 2020 pwd - lrwxrwxrwx 1 root root 4 Aug 4 2021 rbash -> bash - -rwxr-xr-x 1 root root 52032 Sep 23 2020 readlink - -rwxr-xr-x 1 root root 72704 Sep 23 2020 rm - -rwxr-xr-x 1 root root 52032 Sep 23 2020 rmdir - -rwxr-xr-x 1 root root 27472 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 122224 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Feb 9 03:46 sh -> dash - -rwxr-xr-x 1 root root 43808 Sep 23 2020 sleep - -rwxr-xr-x 1 root root 84928 Sep 23 2020 stty - -rwsr-xr-x 1 root root 71912 Jul 28 2021 su - -rwxr-xr-x 1 root root 39744 Sep 23 2020 sync - -rwxr-xr-x 1 root root 531928 Feb 16 2021 tar - -rwxr-xr-x 1 root root 14456 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 101408 Sep 23 2020 touch - -rwxr-xr-x 1 root root 39680 Sep 23 2020 true - -rwxr-xr-x 1 root root 14328 Feb 26 2021 ulockmgr_server - -rwsr-xr-x 1 root root 35040 Jul 28 2021 umount - -rwxr-xr-x 1 root root 39744 Sep 23 2020 uname - -rwxr-xr-x 2 root root 2346 Mar 2 2021 uncompress - -rwxr-xr-x 1 root root 147176 Sep 23 2020 vdir - -rwxr-xr-x 1 root root 63744 Jul 28 2021 wdctl - lrwxrwxrwx 1 root root 8 Nov 6 2019 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Mar 2 2021 zcat - -rwxr-xr-x 1 root root 1678 Mar 2 2021 zcmp - -rwxr-xr-x 1 root root 5880 Mar 2 2021 zdiff - -rwxr-xr-x 1 root root 29 Mar 2 2021 zegrep - -rwxr-xr-x 1 root root 29 Mar 2 2021 zfgrep - -rwxr-xr-x 1 root root 2081 Mar 2 2021 zforce - -rwxr-xr-x 1 root root 7585 Mar 2 2021 zgrep - -rwxr-xr-x 1 root root 2206 Mar 2 2021 zless - -rwxr-xr-x 1 root root 1842 Mar 2 2021 zmore - -rwxr-xr-x 1 root root 4553 Mar 2 2021 znew -I: user script /srv/workspace/pbuilder/2906700/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1234376 Aug 5 10:25 bash + -rwxr-xr-x 3 root root 38984 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 38984 Jul 21 2020 bzcat + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Jul 21 2020 bzdiff + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Sep 5 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Jul 21 2020 bzgrep + -rwxr-xr-x 3 root root 38984 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 18424 Jul 21 2020 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 21 2020 bzmore + -rwxr-xr-x 1 root root 43936 Sep 24 2020 cat + -rwxr-xr-x 1 root root 72672 Sep 24 2020 chgrp + -rwxr-xr-x 1 root root 64448 Sep 24 2020 chmod + -rwxr-xr-x 1 root root 72672 Sep 24 2020 chown + -rwxr-xr-x 1 root root 151168 Sep 24 2020 cp + -rwxr-xr-x 1 root root 125560 Dec 11 2020 dash + -rwxr-xr-x 1 root root 113664 Sep 24 2020 date + -rwxr-xr-x 1 root root 80968 Sep 24 2020 dd + -rwxr-xr-x 1 root root 93936 Sep 24 2020 df + -rwxr-xr-x 1 root root 147176 Sep 24 2020 dir + -rwxr-xr-x 1 root root 84440 Jul 29 09:09 dmesg + lrwxrwxrwx 1 root root 8 Nov 8 2019 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Nov 8 2019 domainname -> hostname + -rwxr-xr-x 1 root root 39712 Sep 24 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 39680 Sep 24 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 69032 Jul 29 09:09 findmnt + -rwsr-xr-x 1 root root 34896 Feb 27 2021 fusermount + -rwxr-xr-x 1 root root 203072 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Mar 3 2021 gunzip + -rwxr-xr-x 1 root root 6376 Mar 3 2021 gzexe + -rwxr-xr-x 1 root root 98048 Mar 3 2021 gzip + -rwxr-xr-x 1 root root 22600 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 72840 Sep 24 2020 ln + -rwxr-xr-x 1 root root 56952 Feb 8 2020 login + -rwxr-xr-x 1 root root 147176 Sep 24 2020 ls + -rwxr-xr-x 1 root root 149736 Jul 29 09:09 lsblk + -rwxr-xr-x 1 root root 85184 Sep 24 2020 mkdir + -rwxr-xr-x 1 root root 76896 Sep 24 2020 mknod + -rwxr-xr-x 1 root root 48064 Sep 24 2020 mktemp + -rwxr-xr-x 1 root root 59632 Jul 29 09:09 more + -rwsr-xr-x 1 root root 55528 Jul 29 09:09 mount + -rwxr-xr-x 1 root root 18664 Jul 29 09:09 mountpoint + -rwxr-xr-x 1 root root 147080 Sep 24 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 19 2021 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 43872 Sep 24 2020 pwd + lrwxrwxrwx 1 root root 4 Aug 5 10:25 rbash -> bash + -rwxr-xr-x 1 root root 52032 Sep 24 2020 readlink + -rwxr-xr-x 1 root root 72704 Sep 24 2020 rm + -rwxr-xr-x 1 root root 52032 Sep 24 2020 rmdir + -rwxr-xr-x 1 root root 27472 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 122224 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Jan 10 18:55 sh -> bash + lrwxrwxrwx 1 root root 4 Jan 7 23:24 sh.distrib -> dash + -rwxr-xr-x 1 root root 43808 Sep 24 2020 sleep + -rwxr-xr-x 1 root root 84928 Sep 24 2020 stty + -rwsr-xr-x 1 root root 71912 Jul 29 09:09 su + -rwxr-xr-x 1 root root 39744 Sep 24 2020 sync + -rwxr-xr-x 1 root root 531928 Feb 17 2021 tar + -rwxr-xr-x 1 root root 14456 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 101408 Sep 24 2020 touch + -rwxr-xr-x 1 root root 39680 Sep 24 2020 true + -rwxr-xr-x 1 root root 14328 Feb 27 2021 ulockmgr_server + -rwsr-xr-x 1 root root 35040 Jul 29 09:09 umount + -rwxr-xr-x 1 root root 39744 Sep 24 2020 uname + -rwxr-xr-x 2 root root 2346 Mar 3 2021 uncompress + -rwxr-xr-x 1 root root 147176 Sep 24 2020 vdir + -rwxr-xr-x 1 root root 63744 Jul 29 09:09 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Mar 3 2021 zcat + -rwxr-xr-x 1 root root 1678 Mar 3 2021 zcmp + -rwxr-xr-x 1 root root 5880 Mar 3 2021 zdiff + -rwxr-xr-x 1 root root 29 Mar 3 2021 zegrep + -rwxr-xr-x 1 root root 29 Mar 3 2021 zfgrep + -rwxr-xr-x 1 root root 2081 Mar 3 2021 zforce + -rwxr-xr-x 1 root root 7585 Mar 3 2021 zgrep + -rwxr-xr-x 1 root root 2206 Mar 3 2021 zless + -rwxr-xr-x 1 root root 1842 Mar 3 2021 zmore + -rwxr-xr-x 1 root root 4553 Mar 3 2021 znew +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -272,7 +306,7 @@ Get: 60 http://deb.debian.org/debian bullseye/main amd64 libspoa-dev amd64 4.0.7+ds-1+b1 [7012 B] Get: 61 http://deb.debian.org/debian bullseye/main amd64 libthread-pool-dev all 3.0.2-1 [4376 B] Get: 62 http://deb.debian.org/debian bullseye/main amd64 rampler amd64 2.0.0-1 [21.7 kB] -Fetched 29.9 MB in 0s (92.7 MB/s) +Fetched 29.9 MB in 1s (22.4 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package bsdextrautils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19655 files and directories currently installed.) @@ -536,7 +570,8 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/racon-1.4.20/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../racon_1.4.20-1_source.changes +hostname: Name or service not known +I: Running cd /build/racon-1.4.20/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../racon_1.4.20-1_source.changes dpkg-buildpackage: info: source package racon dpkg-buildpackage: info: source version 1.4.20-1 dpkg-buildpackage: info: source distribution unstable @@ -587,54 +622,54 @@ sed -i 's/-lpthread/-pthread/' obj-*/CMakeFiles/racon_test.dir/link.txt make[1]: Leaving directory '/build/racon-1.4.20' dh_auto_build -O--buildsystem=cmake - cd obj-x86_64-linux-gnu && make -j16 "INSTALL=install --strip-program=true" VERBOSE=1 + cd obj-x86_64-linux-gnu && make -j15 "INSTALL=install --strip-program=true" VERBOSE=1 make[1]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' /usr/bin/cmake -S/build/racon-1.4.20 -B/build/racon-1.4.20/obj-x86_64-linux-gnu --check-build-system CMakeFiles/Makefile.cmake 0 /usr/bin/cmake -E cmake_progress_start /build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles /build/racon-1.4.20/obj-x86_64-linux-gnu//CMakeFiles/progress.marks make -f CMakeFiles/Makefile2 all make[2]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' make -f CMakeFiles/racon_test.dir/build.make CMakeFiles/racon_test.dir/depend -make -f CMakeFiles/racon.dir/build.make CMakeFiles/racon.dir/depend make[3]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' cd /build/racon-1.4.20/obj-x86_64-linux-gnu && /usr/bin/cmake -E cmake_depends "Unix Makefiles" /build/racon-1.4.20 /build/racon-1.4.20 /build/racon-1.4.20/obj-x86_64-linux-gnu /build/racon-1.4.20/obj-x86_64-linux-gnu /build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/DependInfo.cmake --color= +make -f CMakeFiles/racon.dir/build.make CMakeFiles/racon.dir/depend make[3]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' cd /build/racon-1.4.20/obj-x86_64-linux-gnu && /usr/bin/cmake -E cmake_depends "Unix Makefiles" /build/racon-1.4.20 /build/racon-1.4.20 /build/racon-1.4.20/obj-x86_64-linux-gnu /build/racon-1.4.20/obj-x86_64-linux-gnu /build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon.dir/DependInfo.cmake --color= -Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/DependInfo.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/depend.internal". -Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/CMakeDirectoryInformation.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/depend.internal". Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon.dir/DependInfo.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon.dir/depend.internal". Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/CMakeDirectoryInformation.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon.dir/depend.internal". -Scanning dependencies of target racon_test +Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/DependInfo.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/depend.internal". +Dependee "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/CMakeDirectoryInformation.cmake" is newer than depender "/build/racon-1.4.20/obj-x86_64-linux-gnu/CMakeFiles/racon_test.dir/depend.internal". Scanning dependencies of target racon make[3]: Leaving directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' -make[3]: Leaving directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' -make -f CMakeFiles/racon_test.dir/build.make CMakeFiles/racon_test.dir/build make -f CMakeFiles/racon.dir/build.make CMakeFiles/racon.dir/build make[3]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' +Scanning dependencies of target racon_test +make[3]: Leaving directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' +make -f CMakeFiles/racon_test.dir/build.make CMakeFiles/racon_test.dir/build +[ 7%] Building CXX object CMakeFiles/racon.dir/src/main.cpp.o +/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/main.cpp.o -c /build/racon-1.4.20/src/main.cpp make[3]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' -[ 7%] Building CXX object CMakeFiles/racon_test.dir/test/racon_test.cpp.o [ 14%] Building CXX object CMakeFiles/racon.dir/src/logger.cpp.o -[ 21%] Building CXX object CMakeFiles/racon.dir/src/overlap.cpp.o -[ 28%] Building CXX object CMakeFiles/racon_test.dir/src/sequence.cpp.o -[ 35%] Building CXX object CMakeFiles/racon_test.dir/src/logger.cpp.o -[ 50%] Building CXX object CMakeFiles/racon.dir/src/main.cpp.o -[ 50%] Building CXX object CMakeFiles/racon.dir/src/polisher.cpp.o -[ 64%] Building CXX object CMakeFiles/racon_test.dir/src/overlap.cpp.o -[ 64%] Building CXX object CMakeFiles/racon_test.dir/src/polisher.cpp.o -/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/test/racon_test.cpp.o -c /build/racon-1.4.20/test/racon_test.cpp /usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/logger.cpp.o -c /build/racon-1.4.20/src/logger.cpp -[ 85%] Building CXX object CMakeFiles/racon_test.dir/src/window.cpp.o -[ 85%] Building CXX object CMakeFiles/racon.dir/src/sequence.cpp.o -[ 85%] Building CXX object CMakeFiles/racon.dir/src/window.cpp.o +[ 21%] Building CXX object CMakeFiles/racon.dir/src/polisher.cpp.o +/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/polisher.cpp.o -c /build/racon-1.4.20/src/polisher.cpp +[ 28%] Building CXX object CMakeFiles/racon_test.dir/test/racon_test.cpp.o +[ 35%] Building CXX object CMakeFiles/racon_test.dir/src/polisher.cpp.o +/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/test/racon_test.cpp.o -c /build/racon-1.4.20/test/racon_test.cpp +/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/polisher.cpp.o -c /build/racon-1.4.20/src/polisher.cpp +[ 42%] Building CXX object CMakeFiles/racon_test.dir/src/logger.cpp.o +[ 50%] Building CXX object CMakeFiles/racon.dir/src/overlap.cpp.o +[ 57%] Building CXX object CMakeFiles/racon_test.dir/src/overlap.cpp.o /usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/overlap.cpp.o -c /build/racon-1.4.20/src/overlap.cpp -/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/sequence.cpp.o -c /build/racon-1.4.20/src/sequence.cpp /usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/logger.cpp.o -c /build/racon-1.4.20/src/logger.cpp -/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/main.cpp.o -c /build/racon-1.4.20/src/main.cpp -/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/polisher.cpp.o -c /build/racon-1.4.20/src/polisher.cpp -/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/polisher.cpp.o -c /build/racon-1.4.20/src/polisher.cpp -/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/sequence.cpp.o -c /build/racon-1.4.20/src/sequence.cpp +[ 64%] Building CXX object CMakeFiles/racon_test.dir/src/sequence.cpp.o +[ 71%] Building CXX object CMakeFiles/racon.dir/src/sequence.cpp.o /usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/overlap.cpp.o -c /build/racon-1.4.20/src/overlap.cpp -/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/window.cpp.o -c /build/racon-1.4.20/src/window.cpp +/usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/sequence.cpp.o -c /build/racon-1.4.20/src/sequence.cpp +/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/sequence.cpp.o -c /build/racon-1.4.20/src/sequence.cpp +[ 78%] Building CXX object CMakeFiles/racon.dir/src/window.cpp.o /usr/bin/c++ -DRACON_VERSION=\"v1.4.20\" -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -pthread -std=c++11 -o CMakeFiles/racon.dir/src/window.cpp.o -c /build/racon-1.4.20/src/window.cpp +[ 85%] Building CXX object CMakeFiles/racon_test.dir/src/window.cpp.o +/usr/bin/c++ -I/build/racon-1.4.20/src -I/build/racon-1.4.20/obj-x86_64-linux-gnu/config -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -std=c++11 -o CMakeFiles/racon_test.dir/src/window.cpp.o -c /build/racon-1.4.20/src/window.cpp [ 92%] Linking CXX executable bin/racon_test /usr/bin/cmake -E cmake_link_script CMakeFiles/racon_test.dir/link.txt --verbose=1 /usr/bin/c++ -g -O2 -fdebug-prefix-map=/build/racon-1.4.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wdate-time -D_FORTIFY_SOURCE=2 -Wall -Wextra -pedantic -Wl,-z,relro -Wl,-z,now -rdynamic CMakeFiles/racon_test.dir/test/racon_test.cpp.o CMakeFiles/racon_test.dir/src/logger.cpp.o CMakeFiles/racon_test.dir/src/polisher.cpp.o CMakeFiles/racon_test.dir/src/overlap.cpp.o CMakeFiles/racon_test.dir/src/sequence.cpp.o CMakeFiles/racon_test.dir/src/window.cpp.o -o bin/racon_test -lspoa -ledlib /usr/lib/x86_64-linux-gnu/libgtest_main.a -lz /usr/lib/x86_64-linux-gnu/libgtest.a -pthread @@ -656,119 +691,119 @@ [----------] Global test environment set-up. [----------] 5 tests from RaconInitializeTest [ RUN ] RaconInitializeTest.PolisherTypeError -[ OK ] RaconInitializeTest.PolisherTypeError (1 ms) +[ OK ] RaconInitializeTest.PolisherTypeError (13 ms) [ RUN ] RaconInitializeTest.WindowLengthError -[ OK ] RaconInitializeTest.WindowLengthError (1 ms) +[ OK ] RaconInitializeTest.WindowLengthError (5 ms) [ RUN ] RaconInitializeTest.SequencesPathExtensionError -[ OK ] RaconInitializeTest.SequencesPathExtensionError (1 ms) +[ OK ] RaconInitializeTest.SequencesPathExtensionError (2 ms) [ RUN ] RaconInitializeTest.OverlapsPathExtensionError -[ OK ] RaconInitializeTest.OverlapsPathExtensionError (4 ms) +[ OK ] RaconInitializeTest.OverlapsPathExtensionError (5 ms) [ RUN ] RaconInitializeTest.TargetPathExtensionError -[ OK ] RaconInitializeTest.TargetPathExtensionError (4 ms) -[----------] 5 tests from RaconInitializeTest (11 ms total) +[ OK ] RaconInitializeTest.TargetPathExtensionError (5 ms) +[----------] 5 tests from RaconInitializeTest (30 ms total) [----------] 10 tests from RaconPolishingTest [ RUN ] RaconPolishingTest.ConsensusWithQualities -[racon::Polisher::initialize] loaded target sequences 0.000678 s -[racon::Polisher::initialize] loaded sequences 0.043783 s -[racon::Polisher::initialize] loaded overlaps 0.000589 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.027542 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.043536 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.055541 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.070061 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.083898 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.092455 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.111703 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.132095 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.144906 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.162386 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.175481 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.192916 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.216064 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.227145 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.246696 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.267113 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.282971 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.299604 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.318396 s [racon::Polisher::initialize] aligning overlaps [====================] 0.343176 s -[racon::Polisher::initialize] transformed data into windows 0.002457 s -[racon::Polisher::polish] generating consensus [=> ] 0.033063 s [racon::Polisher::polish] generating consensus [==> ] 0.062975 s [racon::Polisher::polish] generating consensus [===> ] 0.087752 s [racon::Polisher::polish] generating consensus [====> ] 0.104717 s [racon::Polisher::polish] generating consensus [=====> ] 0.115889 s [racon::Polisher::polish] generating consensus [======> ] 0.124011 s [racon::Polisher::polish] generating consensus [=======> ] 0.138366 s [racon::Polisher::polish] generating consensus [========> ] 0.157218 s [racon::Polisher::polish] generating consensus [=========> ] 0.177325 s [racon::Polisher::polish] generating consensus [==========> ] 0.204017 s [racon::Polisher::polish] generating consensus [===========> ] 0.241810 s [racon::Polisher::polish] generating consensus [============> ] 0.273516 s [racon::Polisher::polish] generating consensus [=============> ] 0.336515 s [racon::Polisher::polish] generating consensus [==============> ] 0.388140 s [racon::Polisher::polish] generating consensus [===============> ] 0.412671 s [racon::Polisher::polish] generating consensus [================> ] 0.447537 s [racon::Polisher::polish] generating consensus [=================> ] 0.481251 s [racon::Polisher::polish] generating consensus [==================> ] 0.499204 s [racon::Polisher::polish] generating consensus [===================>] 0.528828 s [racon::Polisher::polish] generating consensus [====================] 0.657846 s -[racon::Polisher::] total = 1.065269 s -[ OK ] RaconPolishingTest.ConsensusWithQualities (1072 ms) +[racon::Polisher::initialize] loaded target sequences 0.021036 s +[racon::Polisher::initialize] loaded sequences 0.924561 s +[racon::Polisher::initialize] loaded overlaps 0.011630 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.111446 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.155383 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.155416 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.191385 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.191416 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.223385 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.235404 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.255399 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.279394 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.319389 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.323376 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.359378 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.403415 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.415388 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.455406 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.503392 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.543417 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.575400 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.623384 s [racon::Polisher::initialize] aligning overlaps [====================] 0.683411 s +[racon::Polisher::initialize] transformed data into windows 0.002656 s +[racon::Polisher::polish] generating consensus [=> ] 0.073245 s [racon::Polisher::polish] generating consensus [==> ] 0.141239 s [racon::Polisher::polish] generating consensus [===> ] 0.185254 s [racon::Polisher::polish] generating consensus [====> ] 0.197270 s [racon::Polisher::polish] generating consensus [=====> ] 0.233250 s [racon::Polisher::polish] generating consensus [======> ] 0.257260 s [racon::Polisher::polish] generating consensus [=======> ] 0.285240 s [racon::Polisher::polish] generating consensus [========> ] 0.321276 s [racon::Polisher::polish] generating consensus [=========> ] 0.374225 s [racon::Polisher::polish] generating consensus [==========> ] 0.425215 s [racon::Polisher::polish] generating consensus [===========> ] 0.497252 s [racon::Polisher::polish] generating consensus [============> ] 0.577246 s [racon::Polisher::polish] generating consensus [=============> ] 0.721253 s [racon::Polisher::polish] generating consensus [==============> ] 0.797230 s [racon::Polisher::polish] generating consensus [===============> ] 0.889246 s [racon::Polisher::polish] generating consensus [================> ] 0.968869 s [racon::Polisher::polish] generating consensus [=================> ] 1.049236 s [racon::Polisher::polish] generating consensus [==================> ] 1.109243 s [racon::Polisher::polish] generating consensus [===================>] 1.153209 s [racon::Polisher::polish] generating consensus [====================] 1.437312 s +[racon::Polisher::] total = 3.155827 s +[ OK ] RaconPolishingTest.ConsensusWithQualities (3169 ms) [ RUN ] RaconPolishingTest.ConsensusWithoutQualities -[racon::Polisher::initialize] loaded target sequences 0.000568 s -[racon::Polisher::initialize] loaded sequences 0.018326 s -[racon::Polisher::initialize] loaded overlaps 0.000324 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.022901 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.039162 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.049852 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.066082 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.080426 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.089351 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.108379 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.128566 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.139483 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.158081 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.174282 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.188047 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.213348 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.222730 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.241066 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.260364 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.277436 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.293030 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.311497 s [racon::Polisher::initialize] aligning overlaps [====================] 0.335740 s -[racon::Polisher::initialize] transformed data into windows 0.000427 s -[racon::Polisher::polish] generating consensus [=> ] 0.039377 s [racon::Polisher::polish] generating consensus [==> ] 0.078958 s [racon::Polisher::polish] generating consensus [===> ] 0.105882 s [racon::Polisher::polish] generating consensus [====> ] 0.131565 s [racon::Polisher::polish] generating consensus [=====> ] 0.139769 s [racon::Polisher::polish] generating consensus [======> ] 0.154729 s [racon::Polisher::polish] generating consensus [=======> ] 0.173444 s [racon::Polisher::polish] generating consensus [========> ] 0.199306 s [racon::Polisher::polish] generating consensus [=========> ] 0.228276 s [racon::Polisher::polish] generating consensus [==========> ] 0.271220 s [racon::Polisher::polish] generating consensus [===========> ] 0.307703 s [racon::Polisher::polish] generating consensus [============> ] 0.357609 s [racon::Polisher::polish] generating consensus [=============> ] 0.438806 s [racon::Polisher::polish] generating consensus [==============> ] 0.496144 s [racon::Polisher::polish] generating consensus [===============> ] 0.557316 s [racon::Polisher::polish] generating consensus [================> ] 0.591680 s [racon::Polisher::polish] generating consensus [=================> ] 0.627463 s [racon::Polisher::polish] generating consensus [==================> ] 0.655464 s [racon::Polisher::polish] generating consensus [===================>] 0.694407 s [racon::Polisher::polish] generating consensus [====================] 0.894308 s -[racon::Polisher::] total = 1.264681 s -[ OK ] RaconPolishingTest.ConsensusWithoutQualities (1271 ms) +[racon::Polisher::initialize] loaded target sequences 0.000670 s +[racon::Polisher::initialize] loaded sequences 0.507473 s +[racon::Polisher::initialize] loaded overlaps 0.000399 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.069834 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.110881 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.122876 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.166885 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.182872 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.210069 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.250889 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.286886 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.321402 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.360552 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.395199 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.430345 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.478858 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.494879 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.534894 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.586899 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.610040 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.655143 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.699252 s [racon::Polisher::initialize] aligning overlaps [====================] 0.739179 s +[racon::Polisher::initialize] transformed data into windows 0.000610 s +[racon::Polisher::polish] generating consensus [=> ] 0.112650 s [racon::Polisher::polish] generating consensus [==> ] 0.159141 s [racon::Polisher::polish] generating consensus [===> ] 0.219019 s [racon::Polisher::polish] generating consensus [====> ] 0.283054 s [racon::Polisher::polish] generating consensus [=====> ] 0.315033 s [racon::Polisher::polish] generating consensus [======> ] 0.335019 s [racon::Polisher::polish] generating consensus [=======> ] 0.388254 s [racon::Polisher::polish] generating consensus [========> ] 0.420971 s [racon::Polisher::polish] generating consensus [=========> ] 0.457559 s [racon::Polisher::polish] generating consensus [==========> ] 0.551135 s [racon::Polisher::polish] generating consensus [===========> ] 0.647049 s [racon::Polisher::polish] generating consensus [============> ] 0.751006 s [racon::Polisher::polish] generating consensus [=============> ] 0.887039 s [racon::Polisher::polish] generating consensus [==============> ] 1.035030 s [racon::Polisher::polish] generating consensus [===============> ] 1.123023 s [racon::Polisher::polish] generating consensus [================> ] 1.218871 s [racon::Polisher::polish] generating consensus [=================> ] 1.287106 s [racon::Polisher::polish] generating consensus [==================> ] 1.307054 s [racon::Polisher::polish] generating consensus [===================>] 1.399219 s [racon::Polisher::polish] generating consensus [====================] 1.711064 s +[racon::Polisher::] total = 2.998229 s +[ OK ] RaconPolishingTest.ConsensusWithoutQualities (3021 ms) [ RUN ] RaconPolishingTest.ConsensusWithQualitiesAndAlignments -[racon::Polisher::initialize] loaded target sequences 0.000572 s -[racon::Polisher::initialize] loaded sequences 0.041719 s -[racon::Polisher::initialize] loaded overlaps 0.026788 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.003257 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.003415 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.003567 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.003764 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.003856 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.004011 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.004190 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.004355 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.004474 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.004613 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.004781 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.004996 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.005221 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.005329 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.005500 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.005786 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.005887 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.006055 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.006239 s [racon::Polisher::initialize] aligning overlaps [====================] 0.006468 s -[racon::Polisher::initialize] transformed data into windows 0.002303 s -[racon::Polisher::polish] generating consensus [=> ] 0.033429 s [racon::Polisher::polish] generating consensus [==> ] 0.063027 s [racon::Polisher::polish] generating consensus [===> ] 0.089503 s [racon::Polisher::polish] generating consensus [====> ] 0.105590 s [racon::Polisher::polish] generating consensus [=====> ] 0.116176 s [racon::Polisher::polish] generating consensus [======> ] 0.124676 s [racon::Polisher::polish] generating consensus [=======> ] 0.135913 s [racon::Polisher::polish] generating consensus [========> ] 0.154968 s [racon::Polisher::polish] generating consensus [=========> ] 0.172363 s [racon::Polisher::polish] generating consensus [==========> ] 0.199391 s [racon::Polisher::polish] generating consensus [===========> ] 0.235506 s [racon::Polisher::polish] generating consensus [============> ] 0.268831 s [racon::Polisher::polish] generating consensus [=============> ] 0.327307 s [racon::Polisher::polish] generating consensus [==============> ] 0.367586 s [racon::Polisher::polish] generating consensus [===============> ] 0.406169 s [racon::Polisher::polish] generating consensus [================> ] 0.430992 s [racon::Polisher::polish] generating consensus [=================> ] 0.466764 s [racon::Polisher::polish] generating consensus [==================> ] 0.488276 s [racon::Polisher::polish] generating consensus [===================>] 0.513409 s [racon::Polisher::polish] generating consensus [====================] 0.633012 s -[racon::Polisher::] total = 0.726630 s -[ OK ] RaconPolishingTest.ConsensusWithQualitiesAndAlignments (731 ms) +[racon::Polisher::initialize] loaded target sequences 0.000682 s +[racon::Polisher::initialize] loaded sequences 0.086286 s +[racon::Polisher::initialize] loaded overlaps 0.432078 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.009474 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.010018 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.010380 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.010832 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.011147 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.011384 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.011676 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.014405 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.014436 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.014444 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.014451 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.014522 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.014530 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.014543 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.014551 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.014559 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.014569 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.014581 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.014592 s [racon::Polisher::initialize] aligning overlaps [====================] 0.014616 s +[racon::Polisher::initialize] transformed data into windows 0.002873 s +[racon::Polisher::polish] generating consensus [=> ] 0.090353 s [racon::Polisher::polish] generating consensus [==> ] 0.118288 s [racon::Polisher::polish] generating consensus [===> ] 0.186330 s [racon::Polisher::polish] generating consensus [====> ] 0.228372 s [racon::Polisher::polish] generating consensus [=====> ] 0.245423 s [racon::Polisher::polish] generating consensus [======> ] 0.245466 s [racon::Polisher::polish] generating consensus [=======> ] 0.274346 s [racon::Polisher::polish] generating consensus [========> ] 0.294349 s [racon::Polisher::polish] generating consensus [=========> ] 0.323553 s [racon::Polisher::polish] generating consensus [==========> ] 0.394617 s [racon::Polisher::polish] generating consensus [===========> ] 0.450328 s [racon::Polisher::polish] generating consensus [============> ] 0.534423 s [racon::Polisher::polish] generating consensus [=============> ] 0.594328 s [racon::Polisher::polish] generating consensus [==============> ] 0.694280 s [racon::Polisher::polish] generating consensus [===============> ] 0.778399 s [racon::Polisher::polish] generating consensus [================> ] 0.802280 s [racon::Polisher::polish] generating consensus [=================> ] 0.862292 s [racon::Polisher::polish] generating consensus [==================> ] 0.886341 s [racon::Polisher::polish] generating consensus [===================>] 0.922327 s [racon::Polisher::polish] generating consensus [====================] 1.102401 s +[racon::Polisher::] total = 1.674512 s +[ OK ] RaconPolishingTest.ConsensusWithQualitiesAndAlignments (1697 ms) [ RUN ] RaconPolishingTest.ConsensusWithoutQualitiesAndWithAlignments -[racon::Polisher::initialize] loaded target sequences 0.000566 s -[racon::Polisher::initialize] loaded sequences 0.018235 s -[racon::Polisher::initialize] loaded overlaps 0.026782 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.002644 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.002821 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.002949 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.003146 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.003216 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.003381 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.003581 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.003733 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.003853 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.004038 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.004172 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.004388 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.004592 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.004752 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.004885 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.005136 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.005235 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.005398 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.005589 s [racon::Polisher::initialize] aligning overlaps [====================] 0.005810 s -[racon::Polisher::initialize] transformed data into windows 0.000474 s -[racon::Polisher::polish] generating consensus [=> ] 0.034281 s [racon::Polisher::polish] generating consensus [==> ] 0.069525 s [racon::Polisher::polish] generating consensus [===> ] 0.098168 s [racon::Polisher::polish] generating consensus [====> ] 0.121117 s [racon::Polisher::polish] generating consensus [=====> ] 0.131572 s [racon::Polisher::polish] generating consensus [======> ] 0.146125 s [racon::Polisher::polish] generating consensus [=======> ] 0.161735 s [racon::Polisher::polish] generating consensus [========> ] 0.184336 s [racon::Polisher::polish] generating consensus [=========> ] 0.204772 s [racon::Polisher::polish] generating consensus [==========> ] 0.239469 s [racon::Polisher::polish] generating consensus [===========> ] 0.274916 s [racon::Polisher::polish] generating consensus [============> ] 0.325099 s [racon::Polisher::polish] generating consensus [=============> ] 0.405644 s [racon::Polisher::polish] generating consensus [==============> ] 0.465814 s [racon::Polisher::polish] generating consensus [===============> ] 0.519479 s [racon::Polisher::polish] generating consensus [================> ] 0.564074 s [racon::Polisher::polish] generating consensus [=================> ] 0.602491 s [racon::Polisher::polish] generating consensus [==================> ] 0.624994 s [racon::Polisher::polish] generating consensus [===================>] 0.657424 s [racon::Polisher::polish] generating consensus [====================] 0.827988 s -[racon::Polisher::] total = 0.890131 s -[ OK ] RaconPolishingTest.ConsensusWithoutQualitiesAndWithAlignments (893 ms) +[racon::Polisher::initialize] loaded target sequences 0.000663 s +[racon::Polisher::initialize] loaded sequences 0.046757 s +[racon::Polisher::initialize] loaded overlaps 0.062898 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.010661 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.010695 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.010703 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.010712 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.010720 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.018685 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.018721 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.018731 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.018741 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.018751 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.018762 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.018771 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.018782 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.018792 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.018802 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.018813 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.018824 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.018834 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.018844 s [racon::Polisher::initialize] aligning overlaps [====================] 0.018854 s +[racon::Polisher::initialize] transformed data into windows 0.000655 s +[racon::Polisher::polish] generating consensus [=> ] 0.059101 s [racon::Polisher::polish] generating consensus [==> ] 0.127102 s [racon::Polisher::polish] generating consensus [===> ] 0.167059 s [racon::Polisher::polish] generating consensus [====> ] 0.223125 s [racon::Polisher::polish] generating consensus [=====> ] 0.231096 s [racon::Polisher::polish] generating consensus [======> ] 0.259085 s [racon::Polisher::polish] generating consensus [=======> ] 0.307063 s [racon::Polisher::polish] generating consensus [========> ] 0.335098 s [racon::Polisher::polish] generating consensus [=========> ] 0.351078 s [racon::Polisher::polish] generating consensus [==========> ] 0.451073 s [racon::Polisher::polish] generating consensus [===========> ] 0.503077 s [racon::Polisher::polish] generating consensus [============> ] 0.571082 s [racon::Polisher::polish] generating consensus [=============> ] 0.803042 s [racon::Polisher::polish] generating consensus [==============> ] 0.859074 s [racon::Polisher::polish] generating consensus [===============> ] 0.967120 s [racon::Polisher::polish] generating consensus [================> ] 1.027166 s [racon::Polisher::polish] generating consensus [=================> ] 1.075083 s [racon::Polisher::polish] generating consensus [==================> ] 1.103065 s [racon::Polisher::polish] generating consensus [===================>] 1.143052 s [racon::Polisher::polish] generating consensus [====================] 1.353790 s +[racon::Polisher::] total = 1.496663 s +[ OK ] RaconPolishingTest.ConsensusWithoutQualitiesAndWithAlignments (1501 ms) [ RUN ] RaconPolishingTest.ConsensusWithQualitiesLargerWindow -[racon::Polisher::initialize] loaded target sequences 0.000579 s -[racon::Polisher::initialize] loaded sequences 0.041748 s -[racon::Polisher::initialize] loaded overlaps 0.000466 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.022065 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.038532 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.049833 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.066952 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.081300 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.089510 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.108835 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.126832 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.140956 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.158491 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.173474 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.188863 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.211607 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.223287 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.241658 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.261516 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.277526 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.292969 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.309986 s [racon::Polisher::initialize] aligning overlaps [====================] 0.336875 s -[racon::Polisher::initialize] transformed data into windows 0.002224 s -[racon::Polisher::polish] generating consensus [=> ] 0.111119 s [racon::Polisher::polish] generating consensus [==> ] 0.122676 s [racon::Polisher::polish] generating consensus [===> ] 0.211410 s [racon::Polisher::polish] generating consensus [====> ] 0.211462 s [racon::Polisher::polish] generating consensus [=====> ] 0.257673 s [racon::Polisher::polish] generating consensus [======> ] 0.257927 s [racon::Polisher::polish] generating consensus [=======> ] 0.304689 s [racon::Polisher::polish] generating consensus [========> ] 0.322504 s [racon::Polisher::polish] generating consensus [=========> ] 0.371140 s [racon::Polisher::polish] generating consensus [==========> ] 0.435469 s [racon::Polisher::polish] generating consensus [===========> ] 0.493662 s [racon::Polisher::polish] generating consensus [============> ] 0.603432 s [racon::Polisher::polish] generating consensus [=============> ] 0.725931 s [racon::Polisher::polish] generating consensus [==============> ] 0.843986 s [racon::Polisher::polish] generating consensus [===============> ] 0.862924 s [racon::Polisher::polish] generating consensus [================> ] 0.972777 s [racon::Polisher::polish] generating consensus [=================> ] 1.010499 s [racon::Polisher::polish] generating consensus [==================> ] 1.073963 s [racon::Polisher::polish] generating consensus [===================>] 1.133783 s [racon::Polisher::polish] generating consensus [====================] 1.377125 s -[racon::Polisher::] total = 1.774343 s -[ OK ] RaconPolishingTest.ConsensusWithQualitiesLargerWindow (1778 ms) +[racon::Polisher::initialize] loaded target sequences 0.000679 s +[racon::Polisher::initialize] loaded sequences 0.050305 s +[racon::Polisher::initialize] loaded overlaps 0.000336 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.033458 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.053449 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.065589 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.082599 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.097536 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.112711 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.131438 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.153505 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.169496 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.195513 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.208869 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.225504 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.255515 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.264626 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.285638 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.306837 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.325498 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.341482 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.365477 s [racon::Polisher::initialize] aligning overlaps [====================] 0.391419 s +[racon::Polisher::initialize] transformed data into windows 0.002731 s +[racon::Polisher::polish] generating consensus [=> ] 0.145673 s [racon::Polisher::polish] generating consensus [==> ] 0.145920 s [racon::Polisher::polish] generating consensus [===> ] 0.255186 s [racon::Polisher::polish] generating consensus [====> ] 0.255241 s [racon::Polisher::polish] generating consensus [=====> ] 0.294314 s [racon::Polisher::polish] generating consensus [======> ] 0.315176 s [racon::Polisher::polish] generating consensus [=======> ] 0.344228 s [racon::Polisher::polish] generating consensus [========> ] 0.391251 s [racon::Polisher::polish] generating consensus [=========> ] 0.429620 s [racon::Polisher::polish] generating consensus [==========> ] 0.514416 s [racon::Polisher::polish] generating consensus [===========> ] 0.583449 s [racon::Polisher::polish] generating consensus [============> ] 0.688883 s [racon::Polisher::polish] generating consensus [=============> ] 0.910776 s [racon::Polisher::polish] generating consensus [==============> ] 0.949577 s [racon::Polisher::polish] generating consensus [===============> ] 1.139202 s [racon::Polisher::polish] generating consensus [================> ] 1.139280 s [racon::Polisher::polish] generating consensus [=================> ] 1.296832 s [racon::Polisher::polish] generating consensus [==================> ] 1.296869 s [racon::Polisher::polish] generating consensus [===================>] 1.336691 s [racon::Polisher::polish] generating consensus [====================] 1.629391 s +[racon::Polisher::] total = 2.097067 s +[ OK ] RaconPolishingTest.ConsensusWithQualitiesLargerWindow (2110 ms) [ RUN ] RaconPolishingTest.ConsensusWithQualitiesEditDistance -[racon::Polisher::initialize] loaded target sequences 0.000577 s -[racon::Polisher::initialize] loaded sequences 0.041968 s -[racon::Polisher::initialize] loaded overlaps 0.000437 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.022291 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.038752 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.050171 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.067253 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.081602 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.089717 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.109121 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.127131 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.141023 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.158631 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.173760 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.188863 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.211951 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.223595 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.241974 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.261633 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.274377 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.291102 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.312993 s [racon::Polisher::initialize] aligning overlaps [====================] 0.336308 s -[racon::Polisher::initialize] transformed data into windows 0.002398 s -[racon::Polisher::polish] generating consensus [=> ] 0.030491 s [racon::Polisher::polish] generating consensus [==> ] 0.059264 s [racon::Polisher::polish] generating consensus [===> ] 0.083614 s [racon::Polisher::polish] generating consensus [====> ] 0.099242 s [racon::Polisher::polish] generating consensus [=====> ] 0.110329 s [racon::Polisher::polish] generating consensus [======> ] 0.119625 s [racon::Polisher::polish] generating consensus [=======> ] 0.130312 s [racon::Polisher::polish] generating consensus [========> ] 0.148399 s [racon::Polisher::polish] generating consensus [=========> ] 0.169468 s [racon::Polisher::polish] generating consensus [==========> ] 0.195753 s [racon::Polisher::polish] generating consensus [===========> ] 0.231745 s [racon::Polisher::polish] generating consensus [============> ] 0.261260 s [racon::Polisher::polish] generating consensus [=============> ] 0.314533 s [racon::Polisher::polish] generating consensus [==============> ] 0.370945 s [racon::Polisher::polish] generating consensus [===============> ] 0.391072 s [racon::Polisher::polish] generating consensus [================> ] 0.424472 s [racon::Polisher::polish] generating consensus [=================> ] 0.456722 s [racon::Polisher::polish] generating consensus [==================> ] 0.483404 s [racon::Polisher::polish] generating consensus [===================>] 0.507405 s [racon::Polisher::polish] generating consensus [====================] 0.627477 s -[racon::Polisher::] total = 1.022994 s -[ OK ] RaconPolishingTest.ConsensusWithQualitiesEditDistance (1026 ms) +[racon::Polisher::initialize] loaded target sequences 0.000725 s +[racon::Polisher::initialize] loaded sequences 0.051658 s +[racon::Polisher::initialize] loaded overlaps 0.000454 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.039162 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.066105 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.074097 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.094116 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.110118 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.122111 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.146142 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.164781 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.188333 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.211561 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.218108 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.239210 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.265028 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.279256 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.299529 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.323449 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.338142 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.357453 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.374954 s [racon::Polisher::initialize] aligning overlaps [====================] 0.403548 s +[racon::Polisher::initialize] transformed data into windows 0.002714 s +[racon::Polisher::polish] generating consensus [=> ] 0.034561 s [racon::Polisher::polish] generating consensus [==> ] 0.061539 s [racon::Polisher::polish] generating consensus [===> ] 0.089336 s [racon::Polisher::polish] generating consensus [====> ] 0.109625 s [racon::Polisher::polish] generating consensus [=====> ] 0.118518 s [racon::Polisher::polish] generating consensus [======> ] 0.129830 s [racon::Polisher::polish] generating consensus [=======> ] 0.142618 s [racon::Polisher::polish] generating consensus [========> ] 0.163723 s [racon::Polisher::polish] generating consensus [=========> ] 0.183704 s [racon::Polisher::polish] generating consensus [==========> ] 0.211704 s [racon::Polisher::polish] generating consensus [===========> ] 0.247744 s [racon::Polisher::polish] generating consensus [============> ] 0.280485 s [racon::Polisher::polish] generating consensus [=============> ] 0.335763 s [racon::Polisher::polish] generating consensus [==============> ] 0.398557 s [racon::Polisher::polish] generating consensus [===============> ] 0.421001 s [racon::Polisher::polish] generating consensus [================> ] 0.451876 s [racon::Polisher::polish] generating consensus [=================> ] 0.488070 s [racon::Polisher::polish] generating consensus [==================> ] 0.536051 s [racon::Polisher::polish] generating consensus [===================>] 0.587743 s [racon::Polisher::polish] generating consensus [====================] 0.715778 s +[racon::Polisher::] total = 1.196275 s +[ OK ] RaconPolishingTest.ConsensusWithQualitiesEditDistance (1203 ms) [ RUN ] RaconPolishingTest.FragmentCorrectionWithQualities -[racon::Polisher::initialize] loaded target sequences 0.041698 s -[racon::Polisher::initialize] loaded sequences 0.041399 s -[racon::Polisher::initialize] loaded overlaps 0.010585 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.026892 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.038892 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.062885 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.070883 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.090891 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.094891 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.110891 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.122890 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.138899 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.158891 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.170889 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.190899 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.214890 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.234890 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.258897 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.274892 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.294898 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.318893 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.334893 s [racon::Polisher::initialize] aligning overlaps [====================] 0.384242 s -[racon::Polisher::initialize] transformed data into windows 0.004299 s -[racon::Polisher::polish] generating consensus [=> ] 0.027241 s [racon::Polisher::polish] generating consensus [==> ] 0.052788 s [racon::Polisher::polish] generating consensus [===> ] 0.077838 s [racon::Polisher::polish] generating consensus [====> ] 0.078075 s [racon::Polisher::polish] generating consensus [=====> ] 0.097341 s [racon::Polisher::polish] generating consensus [======> ] 0.108990 s [racon::Polisher::polish] generating consensus [=======> ] 0.127761 s [racon::Polisher::polish] generating consensus [========> ] 0.128556 s [racon::Polisher::polish] generating consensus [=========> ] 0.162881 s [racon::Polisher::polish] generating consensus [==========> ] 0.177668 s [racon::Polisher::polish] generating consensus [===========> ] 0.190911 s [racon::Polisher::polish] generating consensus [============> ] 0.198297 s [racon::Polisher::polish] generating consensus [=============> ] 0.213705 s [racon::Polisher::polish] generating consensus [==============> ] 0.214168 s [racon::Polisher::polish] generating consensus [===============> ] 0.251861 s [racon::Polisher::polish] generating consensus [================> ] 0.252221 s [racon::Polisher::polish] generating consensus [=================> ] 0.314128 s [racon::Polisher::polish] generating consensus [==================> ] 0.339672 s [racon::Polisher::polish] generating consensus [===================>] 0.366820 s [racon::Polisher::polish] generating consensus [====================] 0.379752 s -[racon::Polisher::] total = 0.862397 s -[ OK ] RaconPolishingTest.FragmentCorrectionWithQualities (864 ms) +[racon::Polisher::initialize] loaded target sequences 0.050044 s +[racon::Polisher::initialize] loaded sequences 0.051108 s +[racon::Polisher::initialize] loaded overlaps 0.088857 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.041983 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.061873 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.077872 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.093873 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.125880 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.125912 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.141910 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.149902 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.169878 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.197919 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.209887 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.229869 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.255088 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.274511 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.298695 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.319725 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.336104 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.365899 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.382596 s [racon::Polisher::initialize] aligning overlaps [====================] 0.433902 s +[racon::Polisher::initialize] transformed data into windows 0.006040 s +[racon::Polisher::polish] generating consensus [=> ] 0.031593 s [racon::Polisher::polish] generating consensus [==> ] 0.059577 s [racon::Polisher::polish] generating consensus [===> ] 0.087943 s [racon::Polisher::polish] generating consensus [====> ] 0.088651 s [racon::Polisher::polish] generating consensus [=====> ] 0.115033 s [racon::Polisher::polish] generating consensus [======> ] 0.124257 s [racon::Polisher::polish] generating consensus [=======> ] 0.146060 s [racon::Polisher::polish] generating consensus [========> ] 0.146286 s [racon::Polisher::polish] generating consensus [=========> ] 0.182703 s [racon::Polisher::polish] generating consensus [==========> ] 0.198569 s [racon::Polisher::polish] generating consensus [===========> ] 0.213949 s [racon::Polisher::polish] generating consensus [============> ] 0.222371 s [racon::Polisher::polish] generating consensus [=============> ] 0.237450 s [racon::Polisher::polish] generating consensus [==============> ] 0.239386 s [racon::Polisher::polish] generating consensus [===============> ] 0.281810 s [racon::Polisher::polish] generating consensus [================> ] 0.282032 s [racon::Polisher::polish] generating consensus [=================> ] 0.349668 s [racon::Polisher::polish] generating consensus [==================> ] 0.378567 s [racon::Polisher::polish] generating consensus [===================>] 0.413832 s [racon::Polisher::polish] generating consensus [====================] 0.423861 s +[racon::Polisher::] total = 1.054644 s +[ OK ] RaconPolishingTest.FragmentCorrectionWithQualities (1057 ms) [ RUN ] RaconPolishingTest.FragmentCorrectionWithQualitiesFull -[racon::Polisher::initialize] loaded target sequences 0.041700 s -[racon::Polisher::initialize] loaded sequences 0.041590 s -[racon::Polisher::initialize] loaded overlaps 0.010271 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.405143 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.685782 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.938951 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.294689 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.574423 s [racon::Polisher::initialize] aligning overlaps [======> ] 1.814109 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.123120 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.509940 s [racon::Polisher::initialize] aligning overlaps [=========> ] 2.904989 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.253504 s [racon::Polisher::initialize] aligning overlaps [===========> ] 3.540881 s [racon::Polisher::initialize] aligning overlaps [============> ] 3.945812 s [racon::Polisher::initialize] aligning overlaps [=============> ] 4.367875 s [racon::Polisher::initialize] aligning overlaps [==============> ] 4.728919 s [racon::Polisher::initialize] aligning overlaps [===============> ] 5.162982 s [racon::Polisher::initialize] aligning overlaps [================> ] 5.584866 s [racon::Polisher::initialize] aligning overlaps [=================> ] 5.967626 s [racon::Polisher::initialize] aligning overlaps [==================> ] 6.383726 s [racon::Polisher::initialize] aligning overlaps [===================>] 6.814219 s [racon::Polisher::initialize] aligning overlaps [====================] 7.218140 s -[racon::Polisher::initialize] transformed data into windows 0.065900 s -[racon::Polisher::polish] generating consensus [=> ] 1.079135 s [racon::Polisher::polish] generating consensus [==> ] 1.840957 s [racon::Polisher::polish] generating consensus [===> ] 2.712081 s [racon::Polisher::polish] generating consensus [====> ] 3.411674 s [racon::Polisher::polish] generating consensus [=====> ] 4.244939 s [racon::Polisher::polish] generating consensus [======> ] 5.187019 s [racon::Polisher::polish] generating consensus [=======> ] 6.088351 s [racon::Polisher::polish] generating consensus [========> ] 6.773678 s [racon::Polisher::polish] generating consensus [=========> ] 7.607746 s [racon::Polisher::polish] generating consensus [==========> ] 8.640755 s [racon::Polisher::polish] generating consensus [===========> ] 9.513542 s [racon::Polisher::polish] generating consensus [============> ] 10.481216 s [racon::Polisher::polish] generating consensus [=============> ] 11.302180 s [racon::Polisher::polish] generating consensus [==============> ] 12.229178 s [racon::Polisher::polish] generating consensus [===============> ] 13.173564 s [racon::Polisher::polish] generating consensus [================> ] 13.711554 s [racon::Polisher::polish] generating consensus [=================> ] 14.754186 s [racon::Polisher::polish] generating consensus [==================> ] 15.719683 s [racon::Polisher::polish] generating consensus [===================>] 16.677677 s [racon::Polisher::polish] generating consensus [====================] 17.565257 s -[racon::Polisher::] total = 24.946059 s -[ OK ] RaconPolishingTest.FragmentCorrectionWithQualitiesFull (24949 ms) +[racon::Polisher::initialize] loaded target sequences 0.052235 s +[racon::Polisher::initialize] loaded sequences 0.044169 s +[racon::Polisher::initialize] loaded overlaps 0.010899 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.452299 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.763157 s [racon::Polisher::initialize] aligning overlaps [===> ] 1.046894 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.438916 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.750710 s [racon::Polisher::initialize] aligning overlaps [======> ] 2.015973 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.370185 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.791957 s [racon::Polisher::initialize] aligning overlaps [=========> ] 3.226815 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.630674 s [racon::Polisher::initialize] aligning overlaps [===========> ] 3.950661 s [racon::Polisher::initialize] aligning overlaps [============> ] 4.470666 s [racon::Polisher::initialize] aligning overlaps [=============> ] 5.114662 s [racon::Polisher::initialize] aligning overlaps [==============> ] 5.730693 s [racon::Polisher::initialize] aligning overlaps [===============> ] 6.567178 s [racon::Polisher::initialize] aligning overlaps [================> ] 7.366671 s [racon::Polisher::initialize] aligning overlaps [=================> ] 8.210699 s [racon::Polisher::initialize] aligning overlaps [==================> ] 8.890669 s [racon::Polisher::initialize] aligning overlaps [===================>] 9.426664 s [racon::Polisher::initialize] aligning overlaps [====================] 10.006539 s +[racon::Polisher::initialize] transformed data into windows 0.084272 s +[racon::Polisher::polish] generating consensus [=> ] 1.808256 s [racon::Polisher::polish] generating consensus [==> ] 3.077142 s [racon::Polisher::polish] generating consensus [===> ] 4.993227 s [racon::Polisher::polish] generating consensus [====> ] 6.005194 s [racon::Polisher::polish] generating consensus [=====> ] 7.137144 s [racon::Polisher::polish] generating consensus [======> ] 8.318062 s [racon::Polisher::polish] generating consensus [=======> ] 9.408856 s [racon::Polisher::polish] generating consensus [========> ] 10.285142 s [racon::Polisher::polish] generating consensus [=========> ] 11.324347 s [racon::Polisher::polish] generating consensus [==========> ] 12.561207 s [racon::Polisher::polish] generating consensus [===========> ] 13.591623 s [racon::Polisher::polish] generating consensus [============> ] 14.741135 s [racon::Polisher::polish] generating consensus [=============> ] 15.740345 s [racon::Polisher::polish] generating consensus [==============> ] 16.905630 s [racon::Polisher::polish] generating consensus [===============> ] 18.093111 s [racon::Polisher::polish] generating consensus [================> ] 18.873176 s [racon::Polisher::polish] generating consensus [=================> ] 20.254387 s [racon::Polisher::polish] generating consensus [==================> ] 21.457793 s [racon::Polisher::polish] generating consensus [===================>] 22.609132 s [racon::Polisher::polish] generating consensus [====================] 23.720808 s +[racon::Polisher::] total = 33.923690 s +[ OK ] RaconPolishingTest.FragmentCorrectionWithQualitiesFull (33927 ms) [ RUN ] RaconPolishingTest.FragmentCorrectionWithoutQualitiesFull -[racon::Polisher::initialize] loaded target sequences 0.018438 s -[racon::Polisher::initialize] loaded sequences 0.018148 s -[racon::Polisher::initialize] loaded overlaps 0.010303 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.402868 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.684644 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.938460 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.312100 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.606624 s [racon::Polisher::initialize] aligning overlaps [======> ] 1.859668 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.181120 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.581249 s [racon::Polisher::initialize] aligning overlaps [=========> ] 2.987701 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.346989 s [racon::Polisher::initialize] aligning overlaps [===========> ] 3.642452 s [racon::Polisher::initialize] aligning overlaps [============> ] 4.059586 s [racon::Polisher::initialize] aligning overlaps [=============> ] 4.501009 s [racon::Polisher::initialize] aligning overlaps [==============> ] 4.877768 s [racon::Polisher::initialize] aligning overlaps [===============> ] 5.328485 s [racon::Polisher::initialize] aligning overlaps [================> ] 5.765689 s [racon::Polisher::initialize] aligning overlaps [=================> ] 6.171907 s [racon::Polisher::initialize] aligning overlaps [==================> ] 6.600946 s [racon::Polisher::initialize] aligning overlaps [===================>] 7.045914 s [racon::Polisher::initialize] aligning overlaps [====================] 7.464474 s -[racon::Polisher::initialize] transformed data into windows 0.012282 s -[racon::Polisher::polish] generating consensus [=> ] 1.512777 s [racon::Polisher::polish] generating consensus [==> ] 2.619571 s [racon::Polisher::polish] generating consensus [===> ] 3.831874 s [racon::Polisher::polish] generating consensus [====> ] 4.754936 s [racon::Polisher::polish] generating consensus [=====> ] 5.897919 s [racon::Polisher::polish] generating consensus [======> ] 7.159197 s [racon::Polisher::polish] generating consensus [=======> ] 8.442328 s [racon::Polisher::polish] generating consensus [========> ] 9.394767 s [racon::Polisher::polish] generating consensus [=========> ] 10.533503 s [racon::Polisher::polish] generating consensus [==========> ] 11.928795 s [racon::Polisher::polish] generating consensus [===========> ] 13.118722 s [racon::Polisher::polish] generating consensus [============> ] 14.427055 s [racon::Polisher::polish] generating consensus [=============> ] 15.609322 s [racon::Polisher::polish] generating consensus [==============> ] 16.871113 s [racon::Polisher::polish] generating consensus [===============> ] 17.967101 s [racon::Polisher::polish] generating consensus [================> ] 18.569615 s [racon::Polisher::polish] generating consensus [=================> ] 19.933685 s [racon::Polisher::polish] generating consensus [==================> ] 21.187173 s [racon::Polisher::polish] generating consensus [===================>] 22.326510 s [racon::Polisher::polish] generating consensus [====================] 23.382558 s -[racon::Polisher::] total = 30.909053 s -[ OK ] RaconPolishingTest.FragmentCorrectionWithoutQualitiesFull (30911 ms) +[racon::Polisher::initialize] loaded target sequences 0.023009 s +[racon::Polisher::initialize] loaded sequences 0.022801 s +[racon::Polisher::initialize] loaded overlaps 0.013816 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.487197 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.817941 s [racon::Polisher::initialize] aligning overlaps [===> ] 1.113541 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.515604 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.835374 s [racon::Polisher::initialize] aligning overlaps [======> ] 2.097536 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.436391 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.892868 s [racon::Polisher::initialize] aligning overlaps [=========> ] 3.343643 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.777365 s [racon::Polisher::initialize] aligning overlaps [===========> ] 4.105095 s [racon::Polisher::initialize] aligning overlaps [============> ] 4.575599 s [racon::Polisher::initialize] aligning overlaps [=============> ] 5.065233 s [racon::Polisher::initialize] aligning overlaps [==============> ] 5.552232 s [racon::Polisher::initialize] aligning overlaps [===============> ] 6.079940 s [racon::Polisher::initialize] aligning overlaps [================> ] 6.615587 s [racon::Polisher::initialize] aligning overlaps [=================> ] 7.071614 s [racon::Polisher::initialize] aligning overlaps [==================> ] 7.547610 s [racon::Polisher::initialize] aligning overlaps [===================>] 8.099579 s [racon::Polisher::initialize] aligning overlaps [====================] 8.657202 s +[racon::Polisher::initialize] transformed data into windows 0.065560 s +[racon::Polisher::polish] generating consensus [=> ] 2.737816 s [racon::Polisher::polish] generating consensus [==> ] 4.153792 s [racon::Polisher::polish] generating consensus [===> ] 5.809894 s [racon::Polisher::polish] generating consensus [====> ] 7.019009 s [racon::Polisher::polish] generating consensus [=====> ] 8.537981 s [racon::Polisher::polish] generating consensus [======> ] 10.179451 s [racon::Polisher::polish] generating consensus [=======> ] 11.960439 s [racon::Polisher::polish] generating consensus [========> ] 13.293788 s [racon::Polisher::polish] generating consensus [=========> ] 14.841807 s [racon::Polisher::polish] generating consensus [==========> ] 16.645790 s [racon::Polisher::polish] generating consensus [===========> ] 18.125834 s [racon::Polisher::polish] generating consensus [============> ] 19.725801 s [racon::Polisher::polish] generating consensus [=============> ] 21.085797 s [racon::Polisher::polish] generating consensus [==============> ] 22.585779 s [racon::Polisher::polish] generating consensus [===============> ] 23.991312 s [racon::Polisher::polish] generating consensus [================> ] 24.743232 s [racon::Polisher::polish] generating consensus [=================> ] 26.592628 s [racon::Polisher::polish] generating consensus [==================> ] 28.201097 s [racon::Polisher::polish] generating consensus [===================>] 29.676845 s [racon::Polisher::polish] generating consensus [====================] 31.035320 s +[racon::Polisher::] total = 39.821338 s +[ OK ] RaconPolishingTest.FragmentCorrectionWithoutQualitiesFull (39825 ms) [ RUN ] RaconPolishingTest.FragmentCorrectionWithQualitiesFullMhap -[racon::Polisher::initialize] loaded target sequences 0.042004 s -[racon::Polisher::initialize] loaded sequences 0.041754 s -[racon::Polisher::initialize] loaded overlaps 0.009773 s -[racon::Polisher::initialize] aligning overlaps [=> ] 0.403774 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.684100 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.936990 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.293804 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.571702 s [racon::Polisher::initialize] aligning overlaps [======> ] 1.809181 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.115630 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.503886 s [racon::Polisher::initialize] aligning overlaps [=========> ] 2.898477 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.245149 s [racon::Polisher::initialize] aligning overlaps [===========> ] 3.530758 s [racon::Polisher::initialize] aligning overlaps [============> ] 3.936003 s [racon::Polisher::initialize] aligning overlaps [=============> ] 4.356356 s [racon::Polisher::initialize] aligning overlaps [==============> ] 4.718189 s [racon::Polisher::initialize] aligning overlaps [===============> ] 5.151042 s [racon::Polisher::initialize] aligning overlaps [================> ] 5.569193 s [racon::Polisher::initialize] aligning overlaps [=================> ] 5.954935 s [racon::Polisher::initialize] aligning overlaps [==================> ] 6.369825 s [racon::Polisher::initialize] aligning overlaps [===================>] 6.801301 s [racon::Polisher::initialize] aligning overlaps [====================] 7.202858 s -[racon::Polisher::initialize] transformed data into windows 0.063432 s -[racon::Polisher::polish] generating consensus [=> ] 1.057911 s [racon::Polisher::polish] generating consensus [==> ] 1.803365 s [racon::Polisher::polish] generating consensus [===> ] 2.669380 s [racon::Polisher::polish] generating consensus [====> ] 3.339512 s [racon::Polisher::polish] generating consensus [=====> ] 4.151386 s [racon::Polisher::polish] generating consensus [======> ] 5.077707 s [racon::Polisher::polish] generating consensus [=======> ] 5.968164 s [racon::Polisher::polish] generating consensus [========> ] 6.647180 s [racon::Polisher::polish] generating consensus [=========> ] 7.469283 s [racon::Polisher::polish] generating consensus [==========> ] 8.476642 s [racon::Polisher::polish] generating consensus [===========> ] 9.329744 s [racon::Polisher::polish] generating consensus [============> ] 10.296249 s [racon::Polisher::polish] generating consensus [=============> ] 11.100708 s [racon::Polisher::polish] generating consensus [==============> ] 12.017321 s [racon::Polisher::polish] generating consensus [===============> ] 12.958320 s [racon::Polisher::polish] generating consensus [================> ] 13.480479 s [racon::Polisher::polish] generating consensus [=================> ] 14.499980 s [racon::Polisher::polish] generating consensus [==================> ] 15.470156 s [racon::Polisher::polish] generating consensus [===================>] 16.394110 s [racon::Polisher::polish] generating consensus [====================] 17.218714 s -[racon::Polisher::] total = 24.581632 s -[ OK ] RaconPolishingTest.FragmentCorrectionWithQualitiesFullMhap (24585 ms) -[----------] 10 tests from RaconPolishingTest (88080 ms total) +[racon::Polisher::initialize] loaded target sequences 0.053298 s +[racon::Polisher::initialize] loaded sequences 0.050686 s +[racon::Polisher::initialize] loaded overlaps 0.039703 s +[racon::Polisher::initialize] aligning overlaps [=> ] 0.473407 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.792105 s [racon::Polisher::initialize] aligning overlaps [===> ] 1.075626 s [racon::Polisher::initialize] aligning overlaps [====> ] 1.471133 s [racon::Polisher::initialize] aligning overlaps [=====> ] 1.783894 s [racon::Polisher::initialize] aligning overlaps [======> ] 2.058684 s [racon::Polisher::initialize] aligning overlaps [=======> ] 2.417750 s [racon::Polisher::initialize] aligning overlaps [========> ] 2.861389 s [racon::Polisher::initialize] aligning overlaps [=========> ] 3.303279 s [racon::Polisher::initialize] aligning overlaps [==========> ] 3.716904 s [racon::Polisher::initialize] aligning overlaps [===========> ] 4.039730 s [racon::Polisher::initialize] aligning overlaps [============> ] 4.499150 s [racon::Polisher::initialize] aligning overlaps [=============> ] 4.959464 s [racon::Polisher::initialize] aligning overlaps [==============> ] 5.353904 s [racon::Polisher::initialize] aligning overlaps [===============> ] 5.829744 s [racon::Polisher::initialize] aligning overlaps [================> ] 6.275139 s [racon::Polisher::initialize] aligning overlaps [=================> ] 6.698768 s [racon::Polisher::initialize] aligning overlaps [==================> ] 7.183540 s [racon::Polisher::initialize] aligning overlaps [===================>] 7.671129 s [racon::Polisher::initialize] aligning overlaps [====================] 8.103900 s +[racon::Polisher::initialize] transformed data into windows 0.083365 s +[racon::Polisher::polish] generating consensus [=> ] 1.332649 s [racon::Polisher::polish] generating consensus [==> ] 2.272611 s [racon::Polisher::polish] generating consensus [===> ] 3.388679 s [racon::Polisher::polish] generating consensus [====> ] 4.268700 s [racon::Polisher::polish] generating consensus [=====> ] 5.313099 s [racon::Polisher::polish] generating consensus [======> ] 6.488620 s [racon::Polisher::polish] generating consensus [=======> ] 7.592697 s [racon::Polisher::polish] generating consensus [========> ] 8.434465 s [racon::Polisher::polish] generating consensus [=========> ] 9.446140 s [racon::Polisher::polish] generating consensus [==========> ] 10.668664 s [racon::Polisher::polish] generating consensus [===========> ] 11.729379 s [racon::Polisher::polish] generating consensus [============> ] 12.879887 s [racon::Polisher::polish] generating consensus [=============> ] 13.867664 s [racon::Polisher::polish] generating consensus [==============> ] 14.990854 s [racon::Polisher::polish] generating consensus [===============> ] 16.116665 s [racon::Polisher::polish] generating consensus [================> ] 16.748718 s [racon::Polisher::polish] generating consensus [=================> ] 17.980672 s [racon::Polisher::polish] generating consensus [==================> ] 19.120659 s [racon::Polisher::polish] generating consensus [===================>] 20.218567 s [racon::Polisher::polish] generating consensus [====================] 21.268703 s +[racon::Polisher::] total = 29.604744 s +[ OK ] RaconPolishingTest.FragmentCorrectionWithQualitiesFullMhap (29610 ms) +[----------] 10 tests from RaconPolishingTest (117120 ms total) [----------] Global test environment tear-down -[==========] 15 tests from 2 test suites ran. (88091 ms total) +[==========] 15 tests from 2 test suites ran. (117150 ms total) [ PASSED ] 15 tests. make[1]: Leaving directory '/build/racon-1.4.20' create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=cmake dh_prep -O--buildsystem=cmake dh_auto_install -O--buildsystem=cmake - cd obj-x86_64-linux-gnu && make -j16 install DESTDIR=/build/racon-1.4.20/debian/racon AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" + cd obj-x86_64-linux-gnu && make -j15 install DESTDIR=/build/racon-1.4.20/debian/racon AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" make[1]: Entering directory '/build/racon-1.4.20/obj-x86_64-linux-gnu' /usr/bin/cmake -S/build/racon-1.4.20 -B/build/racon-1.4.20/obj-x86_64-linux-gnu --check-build-system CMakeFiles/Makefile.cmake 0 make -f CMakeFiles/Makefile2 preinstall @@ -810,12 +845,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/3289031/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/2906700 and its subdirectories -I: Current time: Sat Feb 11 23:15:50 -12 2023 -I: pbuilder-time-stamp: 1676200550 +I: removing directory /srv/workspace/pbuilder/3289031 and its subdirectories +I: Current time: Mon Jan 10 18:59:37 +14 2022 +I: pbuilder-time-stamp: 1641790777