Diff of the two buildlogs: -- --- b1/build.log 2021-07-27 09:39:09.409552246 +0000 +++ b2/build.log 2021-07-27 09:42:37.945756730 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Aug 29 04:00:07 -12 2022 -I: pbuilder-time-stamp: 1661788807 +I: Current time: Tue Jul 27 23:39:15 +14 2021 +I: pbuilder-time-stamp: 1627378755 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 [./chasquid_1.6-1.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.iPPJbIRV/trustedkeys.kbx': General error -gpgv: Signature made Sun Jan 17 02:04:15 2021 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.bxk5V6iH/trustedkeys.kbx': General error +gpgv: Signature made Mon Jan 18 04:04:15 2021 +14 gpgv: using RSA key DAA6EFF1C627EA1C26B1A692AA230FC45F8C27B1 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./chasquid_1.6-1.dsc @@ -31,135 +31,169 @@ dpkg-source: info: applying 0004-test-Ignore-fexp-in-the-regular-Go-build.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/26424/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/4417/tmp/hooks/D01_modify_environment starting +debug: Running on codethink12-arm64. +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/4417/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/4417/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='arm64' - DEBIAN_FRONTEND='noninteractive' + 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]="aarch64-unknown-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=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=8' - DISTRIBUTION='' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - 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='26424' - PS1='# ' - PS2='> ' + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-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=4417 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.FvP570rkT1/pbuilderrc_S0iN --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.FvP570rkT1/b1 --logfile b1/build.log chasquid_1.6-1.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16: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.FvP570rkT1/pbuilderrc_J6cP --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.FvP570rkT1/b2 --logfile b2/build.log --extrapackages usrmerge chasquid_1.6-1.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink11-arm64 4.15.0-151-generic #157-Ubuntu SMP Fri Jul 9 21:03:48 UTC 2021 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-151-generic #157-Ubuntu SMP Fri Jul 9 21:03:48 UTC 2021 aarch64 GNU/Linux I: ls -l /bin total 5252 - -rwxr-xr-x 1 root root 1282512 Jun 21 2021 bash - -rwxr-xr-x 3 root root 34808 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 34808 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 34808 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 14264 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 39832 Sep 22 2020 cat - -rwxr-xr-x 1 root root 64512 Sep 22 2020 chgrp - -rwxr-xr-x 1 root root 60368 Sep 22 2020 chmod - -rwxr-xr-x 1 root root 64528 Sep 22 2020 chown - -rwxr-xr-x 1 root root 138896 Sep 22 2020 cp - -rwxr-xr-x 1 root root 129544 Dec 10 2020 dash - -rwxr-xr-x 1 root root 101384 Sep 22 2020 date - -rwxr-xr-x 1 root root 80984 Sep 22 2020 dd - -rwxr-xr-x 1 root root 89824 Sep 22 2020 df - -rwxr-xr-x 1 root root 143088 Sep 22 2020 dir - -rwxr-xr-x 1 root root 76152 Feb 7 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 35632 Sep 22 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 31512 Sep 22 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 64856 Feb 7 2021 findmnt - -rwsr-xr-x 1 root root 34824 Feb 26 2021 fusermount - -rwxr-xr-x 1 root root 178400 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 93744 Mar 2 2021 gzip - -rwxr-xr-x 1 root root 18440 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 68720 Sep 22 2020 ln - -rwxr-xr-x 1 root root 52720 Feb 7 2020 login - -rwxr-xr-x 1 root root 143088 Sep 22 2020 ls - -rwxr-xr-x 1 root root 161960 Feb 7 2021 lsblk - -rwxr-xr-x 1 root root 85200 Sep 22 2020 mkdir - -rwxr-xr-x 1 root root 68744 Sep 22 2020 mknod - -rwxr-xr-x 1 root root 43976 Sep 22 2020 mktemp - -rwxr-xr-x 1 root root 51368 Feb 7 2021 more - -rwsr-xr-x 1 root root 51360 Feb 7 2021 mount - -rwxr-xr-x 1 root root 14496 Feb 7 2021 mountpoint - -rwxr-xr-x 1 root root 134808 Sep 22 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 35720 Sep 22 2020 pwd - lrwxrwxrwx 1 root root 4 Jun 21 2021 rbash -> bash - -rwxr-xr-x 1 root root 43872 Sep 22 2020 readlink - -rwxr-xr-x 1 root root 68592 Sep 22 2020 rm - -rwxr-xr-x 1 root root 43880 Sep 22 2020 rmdir - -rwxr-xr-x 1 root root 19208 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 114016 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Aug 28 03:47 sh -> dash - -rwxr-xr-x 1 root root 35656 Sep 22 2020 sleep - -rwxr-xr-x 1 root root 72640 Sep 22 2020 stty - -rwsr-xr-x 1 root root 67776 Feb 7 2021 su - -rwxr-xr-x 1 root root 35672 Sep 22 2020 sync - -rwxr-xr-x 1 root root 535768 Feb 16 2021 tar - -rwxr-xr-x 1 root root 10568 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 89120 Sep 22 2020 touch - -rwxr-xr-x 1 root root 31512 Sep 22 2020 true - -rwxr-xr-x 1 root root 14264 Feb 26 2021 ulockmgr_server - -rwsr-xr-x 1 root root 30880 Feb 7 2021 umount - -rwxr-xr-x 1 root root 35640 Sep 22 2020 uname - -rwxr-xr-x 2 root root 2346 Mar 2 2021 uncompress - -rwxr-xr-x 1 root root 143088 Sep 22 2020 vdir - -rwxr-xr-x 1 root root 59584 Feb 7 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/26424/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1282512 Jun 22 16:26 bash + -rwxr-xr-x 3 root root 34808 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 34808 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 34808 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 14264 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 39832 Sep 23 2020 cat + -rwxr-xr-x 1 root root 64512 Sep 23 2020 chgrp + -rwxr-xr-x 1 root root 60368 Sep 23 2020 chmod + -rwxr-xr-x 1 root root 64528 Sep 23 2020 chown + -rwxr-xr-x 1 root root 138896 Sep 23 2020 cp + -rwxr-xr-x 1 root root 129544 Dec 11 2020 dash + -rwxr-xr-x 1 root root 101384 Sep 23 2020 date + -rwxr-xr-x 1 root root 80984 Sep 23 2020 dd + -rwxr-xr-x 1 root root 89824 Sep 23 2020 df + -rwxr-xr-x 1 root root 143088 Sep 23 2020 dir + -rwxr-xr-x 1 root root 76152 Feb 8 04:38 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 35632 Sep 23 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 31512 Sep 23 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 64856 Feb 8 04:38 findmnt + -rwsr-xr-x 1 root root 34824 Feb 27 06:12 fusermount + -rwxr-xr-x 1 root root 178400 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 gunzip + -rwxr-xr-x 1 root root 6376 Mar 3 13:30 gzexe + -rwxr-xr-x 1 root root 93744 Mar 3 13:30 gzip + -rwxr-xr-x 1 root root 18440 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 68720 Sep 23 2020 ln + -rwxr-xr-x 1 root root 52720 Feb 8 2020 login + -rwxr-xr-x 1 root root 143088 Sep 23 2020 ls + -rwxr-xr-x 1 root root 161960 Feb 8 04:38 lsblk + -rwxr-xr-x 1 root root 85200 Sep 23 2020 mkdir + -rwxr-xr-x 1 root root 68744 Sep 23 2020 mknod + -rwxr-xr-x 1 root root 43976 Sep 23 2020 mktemp + -rwxr-xr-x 1 root root 51368 Feb 8 04:38 more + -rwsr-xr-x 1 root root 51360 Feb 8 04:38 mount + -rwxr-xr-x 1 root root 14496 Feb 8 04:38 mountpoint + -rwxr-xr-x 1 root root 134808 Sep 23 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 19 05:38 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 35720 Sep 23 2020 pwd + lrwxrwxrwx 1 root root 4 Jun 22 16:26 rbash -> bash + -rwxr-xr-x 1 root root 43872 Sep 23 2020 readlink + -rwxr-xr-x 1 root root 68592 Sep 23 2020 rm + -rwxr-xr-x 1 root root 43880 Sep 23 2020 rmdir + -rwxr-xr-x 1 root root 19208 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 114016 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Jul 27 23:39 sh -> bash + lrwxrwxrwx 1 root root 4 Jul 27 23:25 sh.distrib -> dash + -rwxr-xr-x 1 root root 35656 Sep 23 2020 sleep + -rwxr-xr-x 1 root root 72640 Sep 23 2020 stty + -rwsr-xr-x 1 root root 67776 Feb 8 04:38 su + -rwxr-xr-x 1 root root 35672 Sep 23 2020 sync + -rwxr-xr-x 1 root root 535768 Feb 17 23:55 tar + -rwxr-xr-x 1 root root 10568 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 89120 Sep 23 2020 touch + -rwxr-xr-x 1 root root 31512 Sep 23 2020 true + -rwxr-xr-x 1 root root 14264 Feb 27 06:12 ulockmgr_server + -rwsr-xr-x 1 root root 30880 Feb 8 04:38 umount + -rwxr-xr-x 1 root root 35640 Sep 23 2020 uname + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 uncompress + -rwxr-xr-x 1 root root 143088 Sep 23 2020 vdir + -rwxr-xr-x 1 root root 59584 Feb 8 04:38 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Mar 3 13:30 zcat + -rwxr-xr-x 1 root root 1678 Mar 3 13:30 zcmp + -rwxr-xr-x 1 root root 5880 Mar 3 13:30 zdiff + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zegrep + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zfgrep + -rwxr-xr-x 1 root root 2081 Mar 3 13:30 zforce + -rwxr-xr-x 1 root root 7585 Mar 3 13:30 zgrep + -rwxr-xr-x 1 root root 2206 Mar 3 13:30 zless + -rwxr-xr-x 1 root root 1842 Mar 3 13:30 zmore + -rwxr-xr-x 1 root root 4553 Mar 3 13:30 znew +I: user script /srv/workspace/pbuilder/4417/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -268,7 +302,7 @@ Get: 47 http://deb.debian.org/debian bullseye/main arm64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 48 http://deb.debian.org/debian bullseye/main arm64 golang-blitiri-go-spf-dev all 1.1.0-1 [84.8 kB] Get: 49 http://deb.debian.org/debian bullseye/main arm64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 81.9 MB in 2s (54.0 MB/s) +Fetched 81.9 MB in 2s (53.7 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 ... 19646 files and directories currently installed.) @@ -479,8 +513,45 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +The following additional packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl +The following NEW packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl usrmerge +0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. +Need to get 59.5 kB of archives. +After this operation, 157 kB of additional disk space will be used. +Get:1 http://deb.debian.org/debian bullseye/main arm64 libnumber-compare-perl all 0.03-1.1 [6956 B] +Get:2 http://deb.debian.org/debian bullseye/main arm64 libtext-glob-perl all 0.11-1 [8888 B] +Get:3 http://deb.debian.org/debian bullseye/main arm64 libfile-find-rule-perl all 0.34-1 [30.6 kB] +Get:4 http://deb.debian.org/debian bullseye/main arm64 usrmerge all 25 [13.0 kB] +debconf: delaying package configuration, since apt-utils is not installed +Fetched 59.5 kB in 0s (1366 kB/s) +Selecting previously unselected package libnumber-compare-perl. +(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 ... 35604 files and directories currently installed.) +Preparing to unpack .../libnumber-compare-perl_0.03-1.1_all.deb ... +Unpacking libnumber-compare-perl (0.03-1.1) ... +Selecting previously unselected package libtext-glob-perl. +Preparing to unpack .../libtext-glob-perl_0.11-1_all.deb ... +Unpacking libtext-glob-perl (0.11-1) ... +Selecting previously unselected package libfile-find-rule-perl. +Preparing to unpack .../libfile-find-rule-perl_0.34-1_all.deb ... +Unpacking libfile-find-rule-perl (0.34-1) ... +Selecting previously unselected package usrmerge. +Preparing to unpack .../archives/usrmerge_25_all.deb ... +Unpacking usrmerge (25) ... +Setting up libtext-glob-perl (0.11-1) ... +Setting up libnumber-compare-perl (0.03-1.1) ... +Setting up libfile-find-rule-perl (0.34-1) ... +Setting up usrmerge (25) ... +The system has been successfully converted. +Processing triggers for man-db (2.9.4-2) ... +Not building database; man-db/auto-update is not 'true'. I: Building the package -I: Running cd /build/chasquid-1.6/ && 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 > ../chasquid_1.6-1_source.changes +hostname: Temporary failure in name resolution +I: Running cd /build/chasquid-1.6/ && 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 > ../chasquid_1.6-1_source.changes dpkg-buildpackage: info: source package chasquid dpkg-buildpackage: info: source version 1.6-1 dpkg-buildpackage: info: source distribution unstable @@ -501,118 +572,118 @@ make[1]: Entering directory '/build/chasquid-1.6' dh_auto_build -- -ldflags " -X main.version=1.6-1 -X main.sourceDateTs=1606046569" cd obj-aarch64-linux-gnu && go install -trimpath -v -p 8 -ldflags " -X main.version=1.6-1 -X main.sourceDateTs=1606046569" blitiri.com.ar/go/chasquid blitiri.com.ar/go/chasquid/cmd/chasquid-util blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/courier blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/dovecot blitiri.com.ar/go/chasquid/internal/envelope blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/chasquid/internal/maillog blitiri.com.ar/go/chasquid/internal/normalize blitiri.com.ar/go/chasquid/internal/protoio blitiri.com.ar/go/chasquid/internal/protoio/testpb blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/safeio blitiri.com.ar/go/chasquid/internal/set blitiri.com.ar/go/chasquid/internal/smtp blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/testlib blitiri.com.ar/go/chasquid/internal/tlsconst blitiri.com.ar/go/chasquid/internal/trace blitiri.com.ar/go/chasquid/internal/userdb -internal/unsafeheader +unicode/utf8 internal/cpu runtime/internal/sys -internal/race math/bits -unicode/utf8 -runtime/internal/atomic +internal/race +internal/unsafeheader sync/atomic +runtime/internal/atomic unicode internal/nettrace runtime/internal/math -internal/bytealg runtime/cgo google.golang.org/protobuf/internal/flags unicode/utf16 +internal/bytealg internal/testlog -math -google.golang.org/protobuf/internal/set encoding container/list crypto/internal/subtle -crypto/subtle +google.golang.org/protobuf/internal/set +math vendor/golang.org/x/crypto/cryptobyte/asn1 vendor/golang.org/x/crypto/internal/subtle blitiri.com.ar/go/chasquid/internal/set +crypto/subtle golang.org/x/sys/internal/unsafeheader runtime internal/reflectlite sync -google.golang.org/protobuf/internal/pragma internal/singleflight math/rand +google.golang.org/protobuf/internal/pragma errors sort -io -internal/oserror strconv +internal/oserror +io vendor/golang.org/x/net/dns/dnsmessage syscall golang.org/x/text/internal/tag -strings hash -bytes crypto/internal/randutil text/tabwriter +bytes +strings hash/fnv hash/crc32 crypto/hmac vendor/golang.org/x/crypto/hkdf golang.org/x/crypto/pbkdf2 -vendor/golang.org/x/text/transform -golang.org/x/text/transform -reflect crypto +reflect crypto/rc4 -regexp/syntax +vendor/golang.org/x/text/transform +golang.org/x/text/transform bufio path +regexp/syntax html golang.org/x/text/runes golang.org/x/text/width internal/syscall/unix -internal/syscall/execenv time +internal/syscall/execenv regexp -internal/poll context +internal/poll internal/fmtsort encoding/binary -os encoding/base64 -crypto/sha512 -crypto/sha1 -crypto/md5 crypto/cipher -crypto/sha256 crypto/ed25519/internal/edwards25519 +crypto/md5 +crypto/sha1 +crypto/sha512 +crypto/sha256 +os encoding/pem vendor/golang.org/x/crypto/poly1305 golang.org/x/sys/unix -crypto/des +golang.org/x/crypto/scrypt crypto/aes +crypto/des vendor/golang.org/x/crypto/chacha20 -golang.org/x/crypto/scrypt -google.golang.org/protobuf/internal/detrand +fmt path/filepath +google.golang.org/protobuf/internal/detrand os/signal -fmt -net runtime/debug vendor/golang.org/x/crypto/chacha20poly1305 +net io/ioutil os/exec blitiri.com.ar/go/chasquid/internal/safeio -log -go/token flag +log compress/flate +go/token google.golang.org/protobuf/internal/errors encoding/json google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/internal/version +google.golang.org/protobuf/reflect/protoreflect net/url golang.org/x/net/internal/timeseries -google.golang.org/protobuf/reflect/protoreflect text/template/parse -encoding/hex math/big +encoding/hex vendor/golang.org/x/crypto/curve25519 -compress/gzip vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm +compress/gzip vendor/golang.org/x/net/http2/hpack google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs @@ -623,54 +694,56 @@ google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts -text/template vendor/golang.org/x/text/secure/bidirule -google.golang.org/protobuf/proto +text/template mime +google.golang.org/protobuf/proto mime/quotedprintable -net/http/internal -google.golang.org/protobuf/internal/encoding/defval vendor/golang.org/x/net/idna +google.golang.org/protobuf/internal/encoding/defval +net/http/internal blitiri.com.ar/go/chasquid/internal/envelope +golang.org/x/text/unicode/bidi +golang.org/x/text/unicode/norm +golang.org/x/text/internal/language crypto/rand -encoding/asn1 crypto/elliptic -crypto/dsa -golang.org/x/text/unicode/bidi +encoding/asn1 crypto/ed25519 +html/template google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc -html/template crypto/rsa +crypto/dsa vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix golang.org/x/text/secure/bidirule -golang.org/x/text/unicode/norm -golang.org/x/text/internal/language -blitiri.com.ar/go/chasquid/internal/protoio +golang.org/x/text/internal/language/compact blitiri.com.ar/go/chasquid/internal/tlsconst +golang.org/x/net/idna +blitiri.com.ar/go/chasquid/internal/protoio internal/profile runtime/pprof -crypto/ecdsa +golang.org/x/text/language runtime/trace +crypto/ecdsa github.com/docopt/docopt-go golang.org/x/term -golang.org/x/text/internal/language/compact -google.golang.org/protobuf/internal/encoding/tag testing -golang.org/x/net/idna -golang.org/x/text/language -google.golang.org/protobuf/internal/impl -golang.org/x/crypto/ssh/terminal +google.golang.org/protobuf/internal/encoding/tag golang.org/x/text/internal +google.golang.org/protobuf/internal/impl golang.org/x/text/cases +golang.org/x/crypto/ssh/terminal golang.org/x/text/secure/precis -vendor/golang.org/x/net/http/httpproxy +blitiri.com.ar/go/chasquid/internal/normalize +blitiri.com.ar/go/chasquid/internal/auth log/syslog -blitiri.com.ar/go/spf net/textproto +vendor/golang.org/x/net/http/httpproxy blitiri.com.ar/go/chasquid/internal/haproxy crypto/x509 +blitiri.com.ar/go/spf blitiri.com.ar/go/systemd blitiri.com.ar/go/chasquid/internal/testlib blitiri.com.ar/go/log @@ -679,27 +752,25 @@ blitiri.com.ar/go/chasquid/internal/dovecot net/mail blitiri.com.ar/go/chasquid/cmd/mda-lmtp -blitiri.com.ar/go/chasquid/internal/normalize -blitiri.com.ar/go/chasquid/internal/auth crypto/tls google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl -blitiri.com.ar/go/chasquid/internal/protoio/testpb blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/userdb +blitiri.com.ar/go/chasquid/internal/protoio/testpb net/http/httptrace net/smtp net/http blitiri.com.ar/go/chasquid/internal/smtp -golang.org/x/net/context/ctxhttp expvar -net/http/pprof golang.org/x/net/trace +golang.org/x/net/context/ctxhttp +net/http/pprof blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/trace -blitiri.com.ar/go/chasquid/internal/maillog blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/sts +blitiri.com.ar/go/chasquid/internal/maillog blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/cmd/chasquid-util blitiri.com.ar/go/chasquid/cmd/smtp-check @@ -723,13 +794,13 @@ === RUN TestTooMuchRecursion --- PASS: TestTooMuchRecursion (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.00s) +--- PASS: TestAddFile (0.01s) === RUN TestRichFile --- PASS: TestRichFile (0.00s) === RUN TestManyFiles ---- PASS: TestManyFiles (0.00s) +--- PASS: TestManyFiles (0.02s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.015s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.065s === RUN TestDecodeResponse auth_test.go:54: OK: "" failed with response pieces != 3, as per RFC auth_test.go:54: OK: "\x00" failed with response pieces != 3, as per RFC @@ -743,52 +814,52 @@ auth_test.go:54: OK: "\xffa@b\x00\xffa@b\x00pass" failed with bidirule: failed Bidi Rule --- PASS: TestDecodeResponse (0.00s) === RUN TestAuthenticate ---- PASS: TestAuthenticate (0.58s) +--- PASS: TestAuthenticate (0.97s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.18s) +--- PASS: TestMultipleBackends (0.19s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.775s +ok blitiri.com.ar/go/chasquid/internal/auth 1.215s === RUN TestEmptyConfig - testlib.go:26: test directory: "/tmp/testlib_783612088" ---- PASS: TestEmptyConfig (0.01s) + testlib.go:26: test directory: "/tmp/testlib_047043655" +--- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:26: test directory: "/tmp/testlib_680594103" + testlib.go:26: test directory: "/tmp/testlib_510002426" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:26: test directory: "/tmp/testlib_777938090" + testlib.go:26: test directory: "/tmp/testlib_926052881" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:26: test directory: "/tmp/testlib_967656449" + testlib.go:26: test directory: "/tmp/testlib_401454652" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.073s +ok blitiri.com.ar/go/chasquid/internal/config 0.044s === RUN TestMDA - testlib.go:26: test directory: "/tmp/testlib_953294268" ---- PASS: TestMDA (0.00s) + testlib.go:26: test directory: "/tmp/testlib_907069436" +--- PASS: TestMDA (0.05s) === RUN TestMDATimeout _ mda.go:67 Courier.MDA to@local: error: operation timed out ---- PASS: TestMDATimeout (0.10s) +--- PASS: TestMDATimeout (0.11s) === RUN TestMDABadCommandLine _ mda.go:80 Courier.MDA to: error: MDA delivery failed: exec: \"thisdoesnotexist\": executable file not found in $PATH - \"\" _ mda.go:80 Courier.MDA to: error: MDA delivery failed: exit status 1 - \"cat: unrecognized option '--fail_unknown_option'\\nTry 'cat --help' for more information.\\n\" ---- PASS: TestMDABadCommandLine (0.00s) +--- PASS: TestMDABadCommandLine (0.01s) === RUN TestExitCode mda_test.go:72: util/exitcode not found, running from outside repo? --- SKIP: TestExitCode (0.00s) === RUN TestSanitize --- PASS: TestSanitize (0.00s) === RUN TestSMTP - testlib.go:26: test directory: "/tmp/testlib_192872427" -_ smtp.go:130 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::44493: too many colons in address -_ smtp.go:100 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::44493: too many colons in address + testlib.go:26: test directory: "/tmp/testlib_683028779" +_ smtp.go:130 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::36069: too many colons in address +_ smtp.go:100 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::36069: too many colons in address smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" @@ -796,51 +867,51 @@ smtp_test.go:73: fakeServer read: "DATA" smtp_test.go:73: fakeServer read: "QUIT" smtp_test.go:69: fakeServer exiting: EOF ---- PASS: TestSMTP (0.00s) +--- PASS: TestSMTP (0.01s) === RUN TestSMTPErrors - testlib.go:26: test directory: "/tmp/testlib_400399701" + testlib.go:26: test directory: "/tmp/testlib_469971605" smtp_test.go:62: fakeServer got connection -_ smtp.go:141 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:57242->127.0.0.1:38733: i/o timeout -_ smtp.go:100 Courier.SMTP to@to: error: \"127.0.0.1\" returned transient error: Error saying hello: write tcp 127.0.0.1:57242->127.0.0.1:38733: i/o timeout +_ smtp.go:141 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:60790->127.0.0.1:42007: i/o timeout +_ smtp.go:100 Courier.SMTP to@to: error: \"127.0.0.1\" returned transient error: Error saying hello: write tcp 127.0.0.1:60790->127.0.0.1:42007: i/o timeout +_ smtp.go:104 Courier.SMTP to@to: error: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:60790->127.0.0.1:42007: i/o timeout) + smtp_test.go:188: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:60790->127.0.0.1:42007: i/o timeout) smtp_test.go:69: fakeServer exiting: EOF -_ smtp.go:104 Courier.SMTP to@to: error: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:57242->127.0.0.1:38733: i/o timeout) - smtp_test.go:188: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:57242->127.0.0.1:38733: i/o timeout) - testlib.go:26: test directory: "/tmp/testlib_481087152" + testlib.go:26: test directory: "/tmp/testlib_120391920" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" _ smtp.go:198 Courier.SMTP to@to: error: MAIL+RCPT 501 mail error - smtp_test.go:69: fakeServer exiting: EOF smtp_test.go:188: failed as expected: MAIL+RCPT 501 mail error - testlib.go:26: test directory: "/tmp/testlib_445714786" + smtp_test.go:69: fakeServer exiting: EOF + testlib.go:26: test directory: "/tmp/testlib_890723746" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" smtp_test.go:73: fakeServer read: "RCPT TO:" _ smtp.go:198 Courier.SMTP to@to: error: MAIL+RCPT 501 rcpt error - smtp_test.go:69: fakeServer exiting: EOF smtp_test.go:188: failed as expected: MAIL+RCPT 501 rcpt error - testlib.go:26: test directory: "/tmp/testlib_096130788" + smtp_test.go:69: fakeServer exiting: EOF + testlib.go:26: test directory: "/tmp/testlib_268620580" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" smtp_test.go:73: fakeServer read: "RCPT TO:" smtp_test.go:73: fakeServer read: "DATA" _ smtp.go:203 Courier.SMTP to@to: error: DATA 554 data error - smtp_test.go:79: fakeServer exiting: unexpected EOF smtp_test.go:188: failed as expected: DATA 554 data error - testlib.go:26: test directory: "/tmp/testlib_806880182" + smtp_test.go:79: fakeServer exiting: unexpected EOF + testlib.go:26: test directory: "/tmp/testlib_326380534" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" smtp_test.go:73: fakeServer read: "RCPT TO:" smtp_test.go:73: fakeServer read: "DATA" _ smtp.go:212 Courier.SMTP to@to: error: DATA closing 551 data response error - smtp_test.go:69: fakeServer exiting: EOF smtp_test.go:188: failed as expected: DATA closing 551 data response error + smtp_test.go:69: fakeServer exiting: EOF --- PASS: TestSMTPErrors (1.01s) === RUN TestNoMXServer - testlib.go:26: test directory: "/tmp/testlib_528459352" + testlib.go:26: test directory: "/tmp/testlib_334734488" _ smtp.go:81 Courier.SMTP to@to: error: Could not find mail server: smtp_test.go:206: got permanent failure, as expected: Could not find mail server: --- PASS: TestNoMXServer (0.00s) @@ -855,52 +926,52 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 1.126s +ok blitiri.com.ar/go/chasquid/internal/courier 1.282s === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_536994785" -_ domaininfo.go:107 DomainInfo /tmp/testlib_536994785: d1 incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_536994785: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_536994785: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE ---- PASS: TestBasic (0.00s) + testlib.go:26: test directory: "/tmp/testlib_027242427" +_ domaininfo.go:107 DomainInfo /tmp/testlib_027242427: d1 incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_027242427: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_027242427: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE +--- PASS: TestBasic (0.01s) === RUN TestNewDomain - testlib.go:26: test directory: "/tmp/testlib_424562398" -_ domaininfo.go:107 DomainInfo /tmp/testlib_424562398: insecure incoming level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_424562398: insecure outgoing level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:107 DomainInfo /tmp/testlib_424562398: secure incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_424562398: secure outgoing level raised: TLS_SECURE > PLAIN + testlib.go:26: test directory: "/tmp/testlib_141957824" +_ domaininfo.go:107 DomainInfo /tmp/testlib_141957824: insecure incoming level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_141957824: insecure outgoing level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:107 DomainInfo /tmp/testlib_141957824: secure incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_141957824: secure outgoing level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:26: test directory: "/tmp/testlib_928891124" -_ domaininfo.go:107 DomainInfo /tmp/testlib_928891124: pisis incoming level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_928891124: pisis outgoing level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:107 DomainInfo /tmp/testlib_928891124: pisis incoming level raised: TLS_SECURE > TLS_INSECURE -_ domaininfo.go:139 DomainInfo /tmp/testlib_928891124: pisis outgoing level raised: TLS_SECURE > TLS_INSECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_928891124: error: pisis incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_928891124: error: pisis outgoing denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:107 DomainInfo /tmp/testlib_928891124: ssip incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_928891124: ssip outgoing level raised: TLS_SECURE > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_928891124: error: ssip incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_928891124: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_928891124: error: ssip incoming denied: PLAIN < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_928891124: error: ssip outgoing denied: PLAIN < TLS_SECURE + testlib.go:26: test directory: "/tmp/testlib_749858630" +_ domaininfo.go:107 DomainInfo /tmp/testlib_749858630: pisis incoming level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_749858630: pisis outgoing level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:107 DomainInfo /tmp/testlib_749858630: pisis incoming level raised: TLS_SECURE > TLS_INSECURE +_ domaininfo.go:139 DomainInfo /tmp/testlib_749858630: pisis outgoing level raised: TLS_SECURE > TLS_INSECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_749858630: error: pisis incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_749858630: error: pisis outgoing denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:107 DomainInfo /tmp/testlib_749858630: ssip incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_749858630: ssip outgoing level raised: TLS_SECURE > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_749858630: error: ssip incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_749858630: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_749858630: error: ssip incoming denied: PLAIN < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_749858630: error: ssip outgoing denied: PLAIN < TLS_SECURE --- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:26: test directory: "/tmp/testlib_665250588" -_ domaininfo.go:107 DomainInfo /tmp/testlib_665250588: d1 incoming level raised: TLS_SECURE > PLAIN + testlib.go:26: test directory: "/tmp/testlib_019352494" +_ domaininfo.go:107 DomainInfo /tmp/testlib_019352494: d1 incoming level raised: TLS_SECURE > PLAIN --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.018s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.034s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:26: test directory: "/tmp/testlib_662099921" + testlib.go:26: test directory: "/tmp/testlib_662753521" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) === RUN TestNotASocket --- PASS: TestNotASocket (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.033s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.061s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -908,13 +979,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.021s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.049s === RUN TestHandler ---- PASS: TestHandler (0.01s) +--- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.052s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.079s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -939,7 +1010,7 @@ haproxy_test.go:69: testing 18: PROXY TCP6 5::5 6::6 7777 8888 --- PASS: TestBasic (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/haproxy 0.040s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.069s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -947,7 +1018,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.112s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.036s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -957,41 +1028,41 @@ === RUN TestDomainToUnicode --- PASS: TestDomainToUnicode (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.010s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.017s === RUN TestBin - testlib.go:26: test directory: "/tmp/testlib_979587745" + testlib.go:26: test directory: "/tmp/testlib_613275511" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:26: test directory: "/tmp/testlib_949152635" + testlib.go:26: test directory: "/tmp/testlib_998941889" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:26: test directory: "/tmp/testlib_241871205" + testlib.go:26: test directory: "/tmp/testlib_500366491" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:26: test directory: "/tmp/testlib_905925343" + testlib.go:26: test directory: "/tmp/testlib_182286213" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:26: test directory: "/tmp/testlib_345232564" + testlib.go:26: test directory: "/tmp/testlib_658112850" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.014s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.042s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Mon, 29 Aug 2022 16:01:25 +0000 + Message-ID: + Date: Tue, 27 Jul 2021 09:41:43 +0000 In-Reply-To: References: X-Failed-Recipients: pepe@africa.org, ñaca@africa.org, Auto-Submitted: auto-replied MIME-Version: 1.0 Content-Type: multipart/report; report-type=delivery-status; - boundary="r2SMAD7vzak" + boundary="fhB3vyqhu30" - --r2SMAD7vzak + --fhB3vyqhu30 Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1016,7 +1087,7 @@ oh! the humanity! - --r2SMAD7vzak + --fhB3vyqhu30 Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1047,7 +1118,7 @@ - --r2SMAD7vzak + --fhB3vyqhu30 Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1057,76 +1128,72 @@ Data ñaca. - --r2SMAD7vzak-- + --fhB3vyqhu30-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_623561704" -_ queue.go:302 Queue.SendLoop DEOKMx0f9Tw: from from -_ queue.go:361 Queue.SendLoop DEOKMx0f9Tw: nodomain sent -_ queue.go:361 Queue.SendLoop DEOKMx0f9Tw: x@remote sent -_ queue.go:361 Queue.SendLoop DEOKMx0f9Tw: am@loco sent -_ queue.go:336 Queue.SendLoop DEOKMx0f9Tw: all done + testlib.go:26: test directory: "/tmp/testlib_807523967" +_ queue.go:302 Queue.SendLoop 9mD34sahQDU: from from +_ queue.go:361 Queue.SendLoop 9mD34sahQDU: nodomain sent +_ queue.go:361 Queue.SendLoop 9mD34sahQDU: am@loco sent +_ queue.go:361 Queue.SendLoop 9mD34sahQDU: x@remote sent +_ queue.go:336 Queue.SendLoop 9mD34sahQDU: all done --- PASS: TestBasic (0.02s) === RUN TestDSNOnTimeout - testlib.go:26: test directory: "/tmp/testlib_167129675" -_ queue.go:302 Queue.SendLoop mmYV480QYDM: from from@loco -_ queue.go:459 Queue.SendLoop mmYV480QYDM: queued DSN: H7AW0BsZIHY -_ queue.go:336 Queue.SendLoop mmYV480QYDM: all done -_ queue.go:302 Queue.SendLoop H7AW0BsZIHY: from <> -_ queue.go:361 Queue.SendLoop H7AW0BsZIHY: from@loco sent + testlib.go:26: test directory: "/tmp/testlib_128183078" +_ queue.go:302 Queue.SendLoop i08kMKD8DqA: from from@loco +_ queue.go:459 Queue.SendLoop i08kMKD8DqA: queued DSN: Sq4ijNcCKTQ +_ queue.go:336 Queue.SendLoop i08kMKD8DqA: all done +_ queue.go:302 Queue.SendLoop Sq4ijNcCKTQ: from <> +_ queue.go:361 Queue.SendLoop Sq4ijNcCKTQ: from@loco sent +_ queue.go:336 Queue.SendLoop Sq4ijNcCKTQ: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases -_ queue.go:336 Queue.SendLoop H7AW0BsZIHY: all done - testlib.go:26: test directory: "/tmp/testlib_358779055" -_ queue.go:302 Queue.SendLoop tjcma0X6_Ik: from from -_ queue.go:361 Queue.SendLoop tjcma0X6_Ik: ata@hualpa sent + testlib.go:26: test directory: "/tmp/testlib_500042682" +_ queue.go:302 Queue.SendLoop 1pzMR3BcfHE: from from +_ queue.go:361 Queue.SendLoop 1pzMR3BcfHE: ata@hualpa sent +_ queue.go:361 Queue.SendLoop 1pzMR3BcfHE: pq@loco sent +_ queue.go:361 Queue.SendLoop 1pzMR3BcfHE: rs@loco sent +_ queue.go:336 Queue.SendLoop 1pzMR3BcfHE: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue -_ queue.go:369 Queue.SendLoop tjcma0X6_Ik: error: failed to write: rename /tmp/testlib_358779055/.m:tjcma0X6_Ik944306361 /tmp/testlib_358779055/m:tjcma0X6_Ik: no such file or directory -_ queue.go:361 Queue.SendLoop tjcma0X6_Ik: pq@loco sent - testlib.go:26: test directory: "/tmp/testlib_176599748" -_ queue.go:369 Queue.SendLoop tjcma0X6_Ik: error: failed to write: open /tmp/testlib_358779055/.m:tjcma0X6_Ik766305619: no such file or directory -_ queue.go:361 Queue.SendLoop tjcma0X6_Ik: rs@loco sent -_ queue.go:369 Queue.SendLoop tjcma0X6_Ik: error: failed to write: open /tmp/testlib_358779055/.m:tjcma0X6_Ik601902230: no such file or directory -_ queue.go:336 Queue.SendLoop tjcma0X6_Ik: all done -E queue.go:224 failed to remove queue file "/tmp/testlib_358779055/m:tjcma0X6_Ik": remove /tmp/testlib_358779055/m:tjcma0X6_Ik: no such file or directory + testlib.go:26: test directory: "/tmp/testlib_635383189" --- PASS: TestFullQueue (0.00s) -_ queue.go:302 Queue.SendLoop WS0bt4uSFxw: from from === RUN TestPipes -_ queue.go:361 Queue.SendLoop WS0bt4uSFxw: to sent - testlib.go:26: test directory: "/tmp/testlib_692043319" -_ queue.go:369 Queue.SendLoop WS0bt4uSFxw: error: failed to write: open /tmp/testlib_176599748/.m:WS0bt4uSFxw514308138: no such file or directory -_ queue.go:336 Queue.SendLoop WS0bt4uSFxw: all done -E queue.go:224 failed to remove queue file "/tmp/testlib_176599748/m:WS0bt4uSFxw": remove /tmp/testlib_176599748/m:WS0bt4uSFxw: no such file or directory ---- PASS: TestPipes (0.00s) + testlib.go:26: test directory: "/tmp/testlib_321029282" +_ queue.go:302 Queue.SendLoop cp5YR9nSXgk: from from +_ queue.go:361 Queue.SendLoop cp5YR9nSXgk: to sent +_ queue.go:369 Queue.SendLoop cp5YR9nSXgk: error: failed to write: open /tmp/testlib_635383189/.m:cp5YR9nSXgk836960665: no such file or directory +_ queue.go:336 Queue.SendLoop cp5YR9nSXgk: all done +E queue.go:224 failed to remove queue file "/tmp/testlib_635383189/m:cp5YR9nSXgk": remove /tmp/testlib_635383189/m:cp5YR9nSXgk: no such file or directory +--- PASS: TestPipes (0.01s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:26: test directory: "/tmp/testlib_964936577" -_ queue.go:302 Queue.SendLoop eYivh4Fwe8c: from from@loco -_ queue.go:361 Queue.SendLoop eYivh4Fwe8c: to@to sent + testlib.go:26: test directory: "/tmp/testlib_602879012" +_ queue.go:302 Queue.SendLoop IlkiiVDXX3s: from from@loco +_ queue.go:361 Queue.SendLoop IlkiiVDXX3s: to@to sent +_ queue.go:336 Queue.SendLoop IlkiiVDXX3s: all done --- PASS: TestSerialization (0.00s) PASS -_ queue.go:369 Queue.SendLoop eYivh4Fwe8c: error: failed to write: rename /tmp/testlib_964936577/.m:eYivh4Fwe8c512618331 /tmp/testlib_964936577/m:eYivh4Fwe8c: no such file or directory -ok blitiri.com.ar/go/chasquid/internal/queue 0.040s +ok blitiri.com.ar/go/chasquid/internal/queue 0.140s === RUN TestWriteFile - testlib.go:26: test directory: "/tmp/testlib_435476761" + testlib.go:26: test directory: "/tmp/testlib_209822980" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:26: test directory: "/tmp/testlib_428768093" + testlib.go:26: test directory: "/tmp/testlib_744622200" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:26: test directory: "/tmp/testlib_795022231" + testlib.go:26: test directory: "/tmp/testlib_004048490" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.026s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.067s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.027s +ok blitiri.com.ar/go/chasquid/internal/set 0.059s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1140,20 +1207,20 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.11s) +--- PASS: TestLineTooLong (0.14s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.146s -_ server.go:221 Server listening on 127.0.0.1:37671 (SMTP) -2022-08-29 16:01:26.049174 daemon listening on 127.0.0.1:37671 -_ server.go:221 Server listening on 127.0.0.1:40669 (submission) -2022-08-29 16:01:26.049354 daemon listening on 127.0.0.1:40669 -_ server.go:221 Server listening on 127.0.0.1:41311 (submission+TLS) -2022-08-29 16:01:26.049427 daemon listening on 127.0.0.1:41311 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.174s +_ server.go:221 Server listening on 127.0.0.1:34011 (SMTP) +2021-07-27 09:41:44.690124 daemon listening on 127.0.0.1:34011 +_ server.go:221 Server listening on 127.0.0.1:46177 (submission) +2021-07-27 09:41:44.690283 daemon listening on 127.0.0.1:46177 +_ server.go:221 Server listening on 127.0.0.1:34831 (submission+TLS) +2021-07-27 09:41:44.690357 daemon listening on 127.0.0.1:34831 === RUN TestSecLevel -_ conn.go:191 SMTP.Conn 127.0.0.1:33384: error: error completing TLS handshake: EOF - testlib.go:26: test directory: "/tmp/testlib_203069336" -_ domaininfo.go:107 DomainInfo /tmp/testlib_203069336: slc incoming level raised: TLS_CLIENT > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_203069336: error: slc incoming denied: PLAIN < TLS_CLIENT + testlib.go:26: test directory: "/tmp/testlib_695723146" +_ conn.go:191 SMTP.Conn 127.0.0.1:41570: error: error completing TLS handshake: EOF +_ domaininfo.go:107 DomainInfo /tmp/testlib_695723146: slc incoming level raised: TLS_CLIENT > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_695723146: error: slc incoming denied: PLAIN < TLS_CLIENT _ conn.go:520 testconn testconn: error: security level check for slc failed (PLAIN) --- PASS: TestSecLevel (0.00s) === RUN TestIsHeader @@ -1190,159 +1257,161 @@ === RUN TestSanitizeEHLODomain --- PASS: TestSanitizeEHLODomain (0.00s) === RUN TestSimple -_ conn.go:664 SMTP.Conn 127.0.0.1:52172: Queued from from@from to [to@localhost] - mlYw0Xd3Dyk -2022-08-29 16:01:26.153814 mlYw0Xd3Dyk from=from@from queued ip=127.0.0.1:52172 to=[to@localhost] -_ queue.go:302 Queue.SendLoop mlYw0Xd3Dyk: from from@from -_ queue.go:361 Queue.SendLoop mlYw0Xd3Dyk: testuser@localhost sent -2022-08-29 16:01:26.154000 mlYw0Xd3Dyk from=from@from to=testuser@localhost sent +_ conn.go:664 SMTP.Conn 127.0.0.1:44054: Queued from from@from to [to@localhost] - TKbsKfkBZGw +2021-07-27 09:41:44.795921 TKbsKfkBZGw from=from@from queued ip=127.0.0.1:44054 to=[to@localhost] +_ queue.go:302 Queue.SendLoop TKbsKfkBZGw: from from@from +_ queue.go:361 Queue.SendLoop TKbsKfkBZGw: testuser@localhost sent +2021-07-27 09:41:44.796214 TKbsKfkBZGw from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop TKbsKfkBZGw: all done +2021-07-27 09:41:44.796579 TKbsKfkBZGw from=from@from all done --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ queue.go:336 Queue.SendLoop mlYw0Xd3Dyk: all done -2022-08-29 16:01:26.154376 mlYw0Xd3Dyk from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:52174: Queued from from@from to [to@localhost] - P7Ze_vKOqT8 -2022-08-29 16:01:26.160352 P7Ze_vKOqT8 from=from@from queued ip=127.0.0.1:52174 to=[to@localhost] -_ queue.go:302 Queue.SendLoop P7Ze_vKOqT8: from from@from -_ queue.go:361 Queue.SendLoop P7Ze_vKOqT8: testuser@localhost sent -2022-08-29 16:01:26.160529 P7Ze_vKOqT8 from=from@from to=testuser@localhost sent +_ conn.go:664 SMTP.Conn 127.0.0.1:44056: Queued from from@from to [to@localhost] - ibhlXr2IbMI +2021-07-27 09:41:44.809756 ibhlXr2IbMI from=from@from queued ip=127.0.0.1:44056 to=[to@localhost] +_ queue.go:302 Queue.SendLoop ibhlXr2IbMI: from from@from +_ queue.go:361 Queue.SendLoop ibhlXr2IbMI: testuser@localhost sent +2021-07-27 09:41:44.809907 ibhlXr2IbMI from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop ibhlXr2IbMI: all done +2021-07-27 09:41:44.810329 ibhlXr2IbMI from=from@from all done --- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ queue.go:336 Queue.SendLoop P7Ze_vKOqT8: all done -2022-08-29 16:01:26.160840 P7Ze_vKOqT8 from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:52176: Queued from from@from to [to@localhost] - z3b-T_iGl6Q -2022-08-29 16:01:26.166443 z3b-T_iGl6Q from=from@from queued ip=127.0.0.1:52176 to=[to@localhost] -_ queue.go:302 Queue.SendLoop z3b-T_iGl6Q: from from@from -_ queue.go:361 Queue.SendLoop z3b-T_iGl6Q: testuser@localhost sent -2022-08-29 16:01:26.166550 z3b-T_iGl6Q from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop z3b-T_iGl6Q: all done -2022-08-29 16:01:26.166879 z3b-T_iGl6Q from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:52176: Queued from from@from to [to@localhost] - KTh4ELonGH4 -2022-08-29 16:01:26.167331 KTh4ELonGH4 from=from@from queued ip=127.0.0.1:52176 to=[to@localhost] -_ queue.go:302 Queue.SendLoop KTh4ELonGH4: from from@from -_ queue.go:361 Queue.SendLoop KTh4ELonGH4: testuser@localhost sent -2022-08-29 16:01:26.167453 KTh4ELonGH4 from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop KTh4ELonGH4: all done -2022-08-29 16:01:26.167719 KTh4ELonGH4 from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:52176: Queued from from@from to [to@localhost] - C3ynLX5V0tM -2022-08-29 16:01:26.168343 C3ynLX5V0tM from=from@from queued ip=127.0.0.1:52176 to=[to@localhost] -_ queue.go:302 Queue.SendLoop C3ynLX5V0tM: from from@from -_ queue.go:361 Queue.SendLoop C3ynLX5V0tM: testuser@localhost sent -2022-08-29 16:01:26.168482 C3ynLX5V0tM from=from@from to=testuser@localhost sent +_ conn.go:664 SMTP.Conn 127.0.0.1:44058: Queued from from@from to [to@localhost] - j7sjkk3-2iY +2021-07-27 09:41:44.817843 j7sjkk3-2iY from=from@from queued ip=127.0.0.1:44058 to=[to@localhost] +_ queue.go:302 Queue.SendLoop j7sjkk3-2iY: from from@from +_ queue.go:361 Queue.SendLoop j7sjkk3-2iY: testuser@localhost sent +2021-07-27 09:41:44.817992 j7sjkk3-2iY from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop j7sjkk3-2iY: all done +2021-07-27 09:41:44.818357 j7sjkk3-2iY from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:44058: Queued from from@from to [to@localhost] - BPyypa9hSJY +2021-07-27 09:41:44.819599 BPyypa9hSJY from=from@from queued ip=127.0.0.1:44058 to=[to@localhost] +_ queue.go:302 Queue.SendLoop BPyypa9hSJY: from from@from +_ queue.go:361 Queue.SendLoop BPyypa9hSJY: testuser@localhost sent +2021-07-27 09:41:44.819836 BPyypa9hSJY from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop BPyypa9hSJY: all done +2021-07-27 09:41:44.820133 BPyypa9hSJY from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:44058: Queued from from@from to [to@localhost] - ZA4hQn7pHtk +2021-07-27 09:41:44.821274 ZA4hQn7pHtk from=from@from queued ip=127.0.0.1:44058 to=[to@localhost] +_ queue.go:302 Queue.SendLoop ZA4hQn7pHtk: from from@from +_ queue.go:361 Queue.SendLoop ZA4hQn7pHtk: testuser@localhost sent +2021-07-27 09:41:44.821501 ZA4hQn7pHtk from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop ZA4hQn7pHtk: all done +2021-07-27 09:41:44.821812 ZA4hQn7pHtk from=from@from all done --- PASS: TestManyEmails (0.01s) === RUN TestAuth -_ queue.go:336 Queue.SendLoop C3ynLX5V0tM: all done -2022-08-29 16:01:26.168733 C3ynLX5V0tM from=from@from all done -2022-08-29 16:01:26.288369 127.0.0.1:37152 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:37152: Queued from testuser@localhost to [to@localhost] - EDb1UsHteJI -2022-08-29 16:01:26.289614 EDb1UsHteJI from=testuser@localhost queued ip=127.0.0.1:37152 to=[to@localhost] -_ queue.go:302 Queue.SendLoop EDb1UsHteJI: from testuser@localhost -_ queue.go:361 Queue.SendLoop EDb1UsHteJI: testuser@localhost sent -2022-08-29 16:01:26.289783 EDb1UsHteJI from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuth (0.12s) +2021-07-27 09:41:44.973526 127.0.0.1:38644 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:38644: Queued from testuser@localhost to [to@localhost] - g1M7A7z83JU +2021-07-27 09:41:44.975498 g1M7A7z83JU from=testuser@localhost queued ip=127.0.0.1:38644 to=[to@localhost] +_ queue.go:302 Queue.SendLoop g1M7A7z83JU: from testuser@localhost +_ queue.go:361 Queue.SendLoop g1M7A7z83JU: testuser@localhost sent +2021-07-27 09:41:44.975779 g1M7A7z83JU from=testuser@localhost to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop g1M7A7z83JU: all done +2021-07-27 09:41:44.976152 g1M7A7z83JU from=testuser@localhost all done +--- PASS: TestAuth (0.15s) === RUN TestSubmissionWithoutAuth -_ queue.go:336 Queue.SendLoop EDb1UsHteJI: all done -2022-08-29 16:01:26.290125 EDb1UsHteJI from=testuser@localhost all done -_ conn.go:292 SMTP.Conn 127.0.0.1:37154: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ conn.go:292 SMTP.Conn 127.0.0.1:38646: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.01s) === RUN TestAuthOnTLS -2022-08-29 16:01:26.413784 127.0.0.1:33396 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:33396: Queued from testuser@localhost to [to@localhost] - ZKw1C57J0nE -2022-08-29 16:01:26.415012 ZKw1C57J0nE from=testuser@localhost queued ip=127.0.0.1:33396 to=[to@localhost] -_ queue.go:302 Queue.SendLoop ZKw1C57J0nE: from testuser@localhost -_ queue.go:361 Queue.SendLoop ZKw1C57J0nE: testuser@localhost sent -2022-08-29 16:01:26.415171 ZKw1C57J0nE from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnTLS (0.12s) +2021-07-27 09:41:45.114885 127.0.0.1:41582 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:41582: Queued from testuser@localhost to [to@localhost] - usoTOP_MttE +2021-07-27 09:41:45.116747 usoTOP_MttE from=testuser@localhost queued ip=127.0.0.1:41582 to=[to@localhost] +_ queue.go:302 Queue.SendLoop usoTOP_MttE: from testuser@localhost +_ queue.go:361 Queue.SendLoop usoTOP_MttE: testuser@localhost sent +2021-07-27 09:41:45.117031 usoTOP_MttE from=testuser@localhost to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop usoTOP_MttE: all done +2021-07-27 09:41:45.117393 usoTOP_MttE from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.13s) === RUN TestAuthOnSMTP -_ queue.go:336 Queue.SendLoop ZKw1C57J0nE: all done -2022-08-29 16:01:26.415513 ZKw1C57J0nE from=testuser@localhost all done -2022-08-29 16:01:26.534128 127.0.0.1:52184 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:52184: Queued from testuser@localhost to [to@localhost] - GkEXM0XYlg8 -2022-08-29 16:01:26.535370 GkEXM0XYlg8 from=testuser@localhost queued ip=127.0.0.1:52184 to=[to@localhost] -_ queue.go:302 Queue.SendLoop GkEXM0XYlg8: from testuser@localhost -_ queue.go:361 Queue.SendLoop GkEXM0XYlg8: testuser@localhost sent -2022-08-29 16:01:26.535545 GkEXM0XYlg8 from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnSMTP (0.12s) +2021-07-27 09:41:45.274698 127.0.0.1:44066 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:44066: Queued from testuser@localhost to [to@localhost] - BE2fXo2olQ8 +2021-07-27 09:41:45.276737 BE2fXo2olQ8 from=testuser@localhost queued ip=127.0.0.1:44066 to=[to@localhost] +_ queue.go:302 Queue.SendLoop BE2fXo2olQ8: from testuser@localhost +_ queue.go:361 Queue.SendLoop BE2fXo2olQ8: testuser@localhost sent +2021-07-27 09:41:45.277011 BE2fXo2olQ8 from=testuser@localhost to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop BE2fXo2olQ8: all done +2021-07-27 09:41:45.277372 BE2fXo2olQ8 from=testuser@localhost all done +--- PASS: TestAuthOnSMTP (0.16s) === RUN TestBrokenAuth -_ queue.go:336 Queue.SendLoop GkEXM0XYlg8: all done -2022-08-29 16:01:26.535902 GkEXM0XYlg8 from=testuser@localhost all done -_ conn.go:1060 SMTP.Conn 127.0.0.1:37160: error: error authenticating \"user\"@\"broken\": failed to auth -2022-08-29 16:01:26.648206 127.0.0.1:37160 auth failed for user@broken -_ conn.go:292 SMTP.Conn 127.0.0.1:37160: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:292 SMTP.Conn 127.0.0.1:37160: error: unknown<*> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.11s) +_ conn.go:1060 SMTP.Conn 127.0.0.1:38652: error: error authenticating \"user\"@\"broken\": failed to auth +2021-07-27 09:41:45.396067 127.0.0.1:38652 auth failed for user@broken +_ conn.go:292 SMTP.Conn 127.0.0.1:38652: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:292 SMTP.Conn 127.0.0.1:38652: error: unknown<*> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:52188: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:52188: error: too many errors, breaking connection +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:44070: error: too many errors, breaking connection --- PASS: TestWrongMailParsing (0.00s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:292 SMTP.Conn 127.0.0.1:52192: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:292 SMTP.Conn 127.0.0.1:44074: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.01s) === RUN TestRelayForbidden -2022-08-29 16:01:26.658358 127.0.0.1:52196 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:292 SMTP.Conn 127.0.0.1:52196: error: RCPT failed: 503 5.7.1 Relay not allowed +2021-07-27 09:41:45.410925 127.0.0.1:44078 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:292 SMTP.Conn 127.0.0.1:44078: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2022-08-29 16:01:26.776883 127.0.0.1:37172 auth succeeded for testuser@localhost -_ conn.go:292 SMTP.Conn 127.0.0.1:37172: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.13s) +2021-07-27 09:41:45.553814 127.0.0.1:38664 auth succeeded for testuser@localhost +_ conn.go:292 SMTP.Conn 127.0.0.1:38664: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.16s) === RUN TestRcptFailsExistsCheck -_ conn.go:1098 SMTP.Conn 127.0.0.1:52200: error: error checking if user \"to@broken\" exists: failed to check if user exists -2022-08-29 16:01:26.793607 127.0.0.1:52200 rejected from=from@localhost to=[to@broken] - local user does not exist -_ conn.go:292 SMTP.Conn 127.0.0.1:52200: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +_ conn.go:1098 SMTP.Conn 127.0.0.1:44082: error: error checking if user \"to@broken\" exists: failed to check if user exists +2021-07-27 09:41:45.581250 127.0.0.1:44082 rejected from=from@localhost to=[to@broken] - local user does not exist +_ conn.go:292 SMTP.Conn 127.0.0.1:44082: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptFailsExistsCheck (0.01s) === RUN TestTooMuchData -_ conn.go:664 SMTP.Conn 127.0.0.1:52202: Queued from from@from to [to@localhost] - yx8dnT8316I -2022-08-29 16:01:28.718650 yx8dnT8316I from=from@from queued ip=127.0.0.1:52202 to=[to@localhost] -_ queue.go:302 Queue.SendLoop yx8dnT8316I: from from@from -_ queue.go:361 Queue.SendLoop yx8dnT8316I: testuser@localhost sent -2022-08-29 16:01:28.718774 yx8dnT8316I from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop yx8dnT8316I: all done -2022-08-29 16:01:28.737689 yx8dnT8316I from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:52202: Queued from from@from to [to@localhost] - vkmQtzk8WU0 -2022-08-29 16:01:29.255330 vkmQtzk8WU0 from=from@from queued ip=127.0.0.1:52202 to=[to@localhost] -_ queue.go:302 Queue.SendLoop vkmQtzk8WU0: from from@from -_ queue.go:361 Queue.SendLoop vkmQtzk8WU0: testuser@localhost sent -2022-08-29 16:01:29.255475 vkmQtzk8WU0 from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop vkmQtzk8WU0: all done -2022-08-29 16:01:30.426710 vkmQtzk8WU0 from=from@from all done -_ conn.go:292 SMTP.Conn 127.0.0.1:52202: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:664 SMTP.Conn 127.0.0.1:52202: Queued from from@from to [to@localhost] - cA0-jLpLEmo -2022-08-29 16:01:30.698963 cA0-jLpLEmo from=from@from queued ip=127.0.0.1:52202 to=[to@localhost] -_ queue.go:302 Queue.SendLoop cA0-jLpLEmo: from from@from -_ queue.go:361 Queue.SendLoop cA0-jLpLEmo: testuser@localhost sent -2022-08-29 16:01:30.699094 cA0-jLpLEmo from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (3.91s) +_ conn.go:664 SMTP.Conn 127.0.0.1:44084: Queued from from@from to [to@localhost] - 3gnYPJle6rc +2021-07-27 09:41:45.791129 3gnYPJle6rc from=from@from queued ip=127.0.0.1:44084 to=[to@localhost] +_ queue.go:302 Queue.SendLoop 3gnYPJle6rc: from from@from +_ queue.go:361 Queue.SendLoop 3gnYPJle6rc: testuser@localhost sent +2021-07-27 09:41:45.791290 3gnYPJle6rc from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop 3gnYPJle6rc: all done +2021-07-27 09:41:45.836679 3gnYPJle6rc from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:44084: Queued from from@from to [to@localhost] - MO3oIQBDn6k +2021-07-27 09:41:46.092253 MO3oIQBDn6k from=from@from queued ip=127.0.0.1:44084 to=[to@localhost] +_ queue.go:302 Queue.SendLoop MO3oIQBDn6k: from from@from +_ queue.go:361 Queue.SendLoop MO3oIQBDn6k: testuser@localhost sent +2021-07-27 09:41:46.092706 MO3oIQBDn6k from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop MO3oIQBDn6k: all done +2021-07-27 09:41:46.139491 MO3oIQBDn6k from=from@from all done +_ conn.go:292 SMTP.Conn 127.0.0.1:44084: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:664 SMTP.Conn 127.0.0.1:44084: Queued from from@from to [to@localhost] - rtFcOal7bws +2021-07-27 09:41:46.646998 rtFcOal7bws from=from@from queued ip=127.0.0.1:44084 to=[to@localhost] +_ queue.go:302 Queue.SendLoop rtFcOal7bws: from from@from +_ queue.go:361 Queue.SendLoop rtFcOal7bws: testuser@localhost sent +2021-07-27 09:41:46.647436 rtFcOal7bws from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (1.07s) === RUN TestSimpleCommands -_ conn.go:292 SMTP.Conn 127.0.0.1:52206: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. -_ conn.go:292 SMTP.Conn 127.0.0.1:52206: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. ---- PASS: TestSimpleCommands (0.00s) +_ conn.go:292 SMTP.Conn 127.0.0.1:44086: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. +_ conn.go:292 SMTP.Conn 127.0.0.1:44086: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +--- PASS: TestSimpleCommands (0.01s) === RUN TestLongLines -_ conn.go:292 SMTP.Conn 127.0.0.1:52208: error: unknown<> failed: 500 5.5.1 Unknown command -_ conn.go:314 SMTP.Conn 127.0.0.1:52208: error: exiting with error: line too long +_ conn.go:292 SMTP.Conn 127.0.0.1:44088: error: unknown<> failed: 500 5.5.1 Unknown command +_ conn.go:314 SMTP.Conn 127.0.0.1:44088: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.00s) === RUN TestRepeatedStartTLS -_ conn.go:292 SMTP.Conn 127.0.0.1:52212: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ queue.go:336 Queue.SendLoop rtFcOal7bws: all done +2021-07-27 09:41:46.668057 rtFcOal7bws from=from@from all done +_ conn.go:292 SMTP.Conn 127.0.0.1:44092: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestRepeatedStartTLS (0.01s) === RUN TestStartTLSOnTLS -_ conn.go:292 SMTP.Conn 127.0.0.1:33428: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestStartTLSOnTLS (0.04s) +_ conn.go:292 SMTP.Conn 127.0.0.1:41612: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestStartTLSOnTLS (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 4.893s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.205s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -1360,16 +1429,16 @@ --- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.01s) +--- PASS: TestPolicyTooBig (0.14s) === RUN TestCacheBasics - testlib.go:26: test directory: "/tmp/testlib_594046139" + testlib.go:26: test directory: "/tmp/testlib_060864219" sts_test.go:277: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:286: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:300: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:309: cache fetched domErr: ---- PASS: TestCacheBasics (0.01s) +--- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:26: test directory: "/tmp/testlib_102609344" + testlib.go:26: test directory: "/tmp/testlib_250540640" sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} @@ -1378,13 +1447,13 @@ sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} --- PASS: TestCacheBadData (0.00s) === RUN TestCacheRefresh - testlib.go:26: test directory: "/tmp/testlib_830085571" + testlib.go:26: test directory: "/tmp/testlib_616821219" sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 1m40s} sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 1m40s} sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 3m20s} --- PASS: TestCacheRefresh (0.01s) === RUN TestCacheSlashSafe - testlib.go:26: test directory: "/tmp/testlib_225266024" + testlib.go:26: test directory: "/tmp/testlib_101096968" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1392,11 +1461,11 @@ === RUN TestHasSTSRecord --- PASS: TestHasSTSRecord (0.00s) === RUN TestHTTPGet ---- PASS: TestHTTPGet (0.00s) +--- PASS: TestHTTPGet (0.05s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.048s +ok blitiri.com.ar/go/chasquid/internal/sts 0.290s === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_300518405" + testlib.go:26: test directory: "/tmp/testlib_079944131" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:34: recovered: invalid/dangerous directory @@ -1407,47 +1476,47 @@ testlib_test.go:61: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:26: test directory: "/tmp/testlib_587718271" + testlib.go:26: test directory: "/tmp/testlib_242804973" --- PASS: TestRewrite (0.00s) === RUN TestGetFreePort --- PASS: TestGetFreePort (0.00s) === RUN TestWaitFor --- PASS: TestWaitFor (0.02s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.071s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.073s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.008s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.033s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestEmptyLoad - userdb_test.go:38: file: "/tmp/userdb_test487921381" - userdb_test.go:38: file: "/tmp/userdb_test509776128" + userdb_test.go:38: file: "/tmp/userdb_test202530334" + userdb_test.go:38: file: "/tmp/userdb_test748609765" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:38: file: "/tmp/userdb_test744029791" ---- PASS: TestWrite (0.97s) + userdb_test.go:38: file: "/tmp/userdb_test670272768" +--- PASS: TestWrite (1.98s) === RUN TestNew ---- PASS: TestNew (0.12s) +--- PASS: TestNew (0.29s) === RUN TestInvalidUsername - userdb_test.go:38: file: "/tmp/userdb_test058679299" + userdb_test.go:38: file: "/tmp/userdb_test265711668" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:38: file: "/tmp/userdb_test696827782" + userdb_test.go:38: file: "/tmp/userdb_test254875651" --- PASS: TestPlainScheme (0.00s) === RUN TestReload - userdb_test.go:38: file: "/tmp/userdb_test035058344" + userdb_test.go:38: file: "/tmp/userdb_test731110701" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:38: file: "/tmp/userdb_test826335207" ---- PASS: TestRemoveUser (0.12s) + userdb_test.go:38: file: "/tmp/userdb_test508969640" +--- PASS: TestRemoveUser (0.27s) === RUN TestExists - userdb_test.go:38: file: "/tmp/userdb_test146261018" ---- PASS: TestExists (0.12s) + userdb_test.go:38: file: "/tmp/userdb_test023389159" +--- PASS: TestExists (0.34s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.357s +ok blitiri.com.ar/go/chasquid/internal/userdb 2.911s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1511,12 +1580,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/4417/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/4417/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/26424 and its subdirectories -I: Current time: Mon Aug 29 04:02:07 -12 2022 -I: pbuilder-time-stamp: 1661788927 +I: removing directory /srv/workspace/pbuilder/4417 and its subdirectories +I: Current time: Tue Jul 27 23:42:36 +14 2021 +I: pbuilder-time-stamp: 1627378956