Diff of the two buildlogs: -- --- b1/build.log 2022-02-09 22:05:15.323729397 +0000 +++ b2/build.log 2022-02-09 22:06:26.833522942 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue Mar 14 16:25:28 -12 2023 -I: pbuilder-time-stamp: 1678854328 +I: Current time: Thu Feb 10 12:05:16 +14 2022 +I: pbuilder-time-stamp: 1644444316 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.7_lr904_/trustedkeys.kbx': General error -gpgv: Signature made Sun Jan 17 02:04:15 2021 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.vAqwZV3B/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/2418705/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/4151574/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/4151574/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/4151574/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='22a8fc6409c541139941e9062c3f3950' - 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='2418705' - PS1='# ' - PS2='> ' + INVOCATION_ID=43ef4ba11b444f28893e16217e4a3ab6 + 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=4151574 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.DFxNLfTfdK/pbuilderrc_6NJW --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.DFxNLfTfdK/b1 --logfile b1/build.log chasquid_1.6-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.DFxNLfTfdK/pbuilderrc_c6DZ --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.DFxNLfTfdK/b2 --logfile b2/build.log chasquid_1.6-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.15.0-0.bpo.2-amd64 #1 SMP Debian 5.15.5-2~bpo11+1 (2022-01-02) x86_64 GNU/Linux + Linux i-capture-the-hostname 5.10.0-11-amd64 #1 SMP Debian 5.10.92-1 (2022-01-18) 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 Mar 12 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/2418705/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1234376 Aug 5 2021 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 2021 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 2021 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 2021 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 2021 more + -rwsr-xr-x 1 root root 55528 Jul 29 2021 mount + -rwxr-xr-x 1 root root 18664 Jul 29 2021 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 2021 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 Feb 10 12:05 sh -> bash + lrwxrwxrwx 1 root root 4 Feb 7 23:25 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 2021 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 2021 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 2021 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/4151574/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 amd64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 48 http://deb.debian.org/debian bullseye/main amd64 golang-blitiri-go-spf-dev all 1.1.0-1 [84.8 kB] Get: 49 http://deb.debian.org/debian bullseye/main amd64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 86.7 MB in 2s (44.1 MB/s) +Fetched 86.7 MB in 1s (91.6 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.) @@ -480,7 +514,8 @@ Building tag database... -> Finished parsing the build-deps 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: Name or service not known +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 @@ -500,201 +535,201 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/chasquid-1.6' dh_auto_build -- -ldflags " -X main.version=1.6-1 -X main.sourceDateTs=1606046569" - cd obj-x86_64-linux-gnu && go install -trimpath -v -p 16 -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 -crypto/internal/subtle -runtime/internal/sys + cd obj-x86_64-linux-gnu && go install -trimpath -v -p 15 -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 math/bits -unicode/utf8 -internal/race unicode +unicode/utf8 internal/nettrace -google.golang.org/protobuf/internal/flags +runtime/internal/sys +internal/unsafeheader +encoding +internal/race container/list unicode/utf16 -encoding +sync/atomic +google.golang.org/protobuf/internal/flags internal/cpu +runtime/cgo +crypto/internal/subtle runtime/internal/atomic crypto/subtle vendor/golang.org/x/crypto/cryptobyte/asn1 -sync/atomic -runtime/internal/math -runtime/cgo vendor/golang.org/x/crypto/internal/subtle -google.golang.org/protobuf/internal/set blitiri.com.ar/go/chasquid/internal/set +runtime/internal/math golang.org/x/sys/internal/unsafeheader -internal/bytealg +google.golang.org/protobuf/internal/set internal/testlog +internal/bytealg math runtime internal/reflectlite sync -internal/singleflight math/rand google.golang.org/protobuf/internal/pragma +internal/singleflight errors sort +io strconv internal/oserror -io vendor/golang.org/x/net/dns/dnsmessage -golang.org/x/text/internal/tag syscall -strings +golang.org/x/text/internal/tag hash crypto/internal/randutil -bytes text/tabwriter +bytes +strings hash/fnv -crypto/hmac hash/crc32 +crypto/hmac vendor/golang.org/x/crypto/hkdf golang.org/x/crypto/pbkdf2 crypto crypto/rc4 reflect -vendor/golang.org/x/text/transform golang.org/x/text/transform -regexp/syntax +vendor/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 +os internal/fmtsort encoding/binary -os -crypto/cipher -crypto/sha512 -crypto/ed25519/internal/edwards25519 -crypto/md5 encoding/base64 -crypto/sha256 +crypto/md5 +crypto/sha512 +crypto/cipher vendor/golang.org/x/crypto/poly1305 -crypto/sha1 +crypto/sha256 +crypto/ed25519/internal/edwards25519 golang.org/x/sys/unix +crypto/sha1 encoding/pem +fmt google.golang.org/protobuf/internal/detrand path/filepath -fmt os/signal runtime/debug net -crypto/aes crypto/des vendor/golang.org/x/crypto/chacha20 +crypto/aes golang.org/x/crypto/scrypt io/ioutil os/exec blitiri.com.ar/go/chasquid/internal/safeio vendor/golang.org/x/sys/cpu +vendor/golang.org/x/crypto/chacha20poly1305 +log flag google.golang.org/protobuf/internal/errors -log -google.golang.org/protobuf/internal/version go/token -compress/flate net/url +encoding/hex +compress/flate +google.golang.org/protobuf/internal/version encoding/json +vendor/golang.org/x/crypto/curve25519 text/template/parse -encoding/hex math/big -vendor/golang.org/x/crypto/curve25519 vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/net/http2/hpack google.golang.org/protobuf/encoding/protowire -vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/net/http2/hpack mime -golang.org/x/net/internal/timeseries -vendor/golang.org/x/text/unicode/bidi google.golang.org/protobuf/reflect/protoreflect mime/quotedprintable net/http/internal -compress/gzip blitiri.com.ar/go/chasquid/internal/envelope +golang.org/x/net/internal/timeseries +vendor/golang.org/x/text/unicode/bidi golang.org/x/text/unicode/bidi golang.org/x/text/unicode/norm golang.org/x/text/internal/language blitiri.com.ar/go/chasquid/internal/tlsconst runtime/trace +compress/gzip github.com/docopt/docopt-go +golang.org/x/term +testing runtime/pprof internal/profile vendor/golang.org/x/text/secure/bidirule -golang.org/x/term -testing +golang.org/x/text/secure/bidirule text/template google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order -google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts -golang.org/x/text/secure/bidirule vendor/golang.org/x/net/idna -google.golang.org/protobuf/internal/encoding/text golang.org/x/crypto/ssh/terminal +google.golang.org/protobuf/reflect/protoregistry +google.golang.org/protobuf/internal/encoding/text golang.org/x/text/internal/language/compact +golang.org/x/net/idna crypto/rand +crypto/elliptic crypto/dsa encoding/asn1 -crypto/elliptic -google.golang.org/protobuf/proto -golang.org/x/text/language crypto/ed25519 crypto/rsa -golang.org/x/net/idna +golang.org/x/text/language +google.golang.org/protobuf/proto google.golang.org/protobuf/internal/encoding/defval -html/template -crypto/x509/pkix vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +html/template golang.org/x/text/internal -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/filedesc golang.org/x/text/cases crypto/ecdsa +google.golang.org/protobuf/encoding/prototext +google.golang.org/protobuf/internal/filedesc golang.org/x/text/secure/precis blitiri.com.ar/go/chasquid/internal/protoio -google.golang.org/protobuf/internal/encoding/tag blitiri.com.ar/go/chasquid/internal/normalize +google.golang.org/protobuf/internal/encoding/tag blitiri.com.ar/go/chasquid/internal/auth google.golang.org/protobuf/internal/impl log/syslog +crypto/x509 +blitiri.com.ar/go/chasquid/internal/haproxy +blitiri.com.ar/go/systemd 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 vendor/golang.org/x/net/http/httpguts mime/multipart blitiri.com.ar/go/chasquid/internal/dovecot -net/mail blitiri.com.ar/go/chasquid/cmd/mda-lmtp +net/mail crypto/tls google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl blitiri.com.ar/go/chasquid/internal/config -blitiri.com.ar/go/chasquid/internal/userdb blitiri.com.ar/go/chasquid/internal/protoio/testpb +blitiri.com.ar/go/chasquid/internal/userdb net/http/httptrace net/smtp net/http blitiri.com.ar/go/chasquid/internal/smtp +golang.org/x/net/context/ctxhttp expvar 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 @@ -710,7 +745,7 @@ blitiri.com.ar/go/chasquid make[1]: Leaving directory '/build/chasquid-1.6' dh_auto_test -O--buildsystem=golang - cd obj-x86_64-linux-gnu && go test -vet=off -v -p 16 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 + cd obj-x86_64-linux-gnu && go test -vet=off -v -p 15 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 ? blitiri.com.ar/go/chasquid [no test files] ? blitiri.com.ar/go/chasquid/cmd/chasquid-util [no test files] ? blitiri.com.ar/go/chasquid/cmd/mda-lmtp [no test files] @@ -730,7 +765,7 @@ === RUN TestManyFiles --- PASS: TestManyFiles (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.039s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.014s === 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 @@ -744,52 +779,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.74s) +--- PASS: TestAuthenticate (0.42s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.19s) +--- PASS: TestMultipleBackends (0.18s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.969s +ok blitiri.com.ar/go/chasquid/internal/auth 0.612s === RUN TestEmptyConfig - testlib.go:26: test directory: "/tmp/testlib_993353459" + testlib.go:26: test directory: "/tmp/testlib_357422699" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:26: test directory: "/tmp/testlib_264068534" + testlib.go:26: test directory: "/tmp/testlib_370649806" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:26: test directory: "/tmp/testlib_737387933" + testlib.go:26: test directory: "/tmp/testlib_384069077" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:26: test directory: "/tmp/testlib_463133272" + testlib.go:26: test directory: "/tmp/testlib_431690544" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.009s +ok blitiri.com.ar/go/chasquid/internal/config 0.016s === RUN TestMDA - testlib.go:26: test directory: "/tmp/testlib_279344798" + testlib.go:26: test directory: "/tmp/testlib_064195786" --- PASS: TestMDA (0.00s) === RUN TestMDATimeout _ mda.go:67 Courier.MDA to@local: error: operation timed out ---- PASS: TestMDATimeout (0.11s) +--- PASS: TestMDATimeout (0.10s) === 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.02s) +--- PASS: TestMDABadCommandLine (0.00s) === 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_002398053" -_ smtp.go:130 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::39689: too many colons in address -_ smtp.go:100 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::39689: too many colons in address + testlib.go:26: test directory: "/tmp/testlib_847739297" +_ smtp.go:130 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::33781: too many colons in address +_ smtp.go:100 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::33781: 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:" @@ -799,21 +834,21 @@ smtp_test.go:69: fakeServer exiting: EOF --- PASS: TestSMTP (0.00s) === RUN TestSMTPErrors - testlib.go:26: test directory: "/tmp/testlib_877556959" + testlib.go:26: test directory: "/tmp/testlib_791871611" smtp_test.go:62: fakeServer got connection -_ smtp.go:141 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:54560->127.0.0.1:43219: 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:54560->127.0.0.1:43219: 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:54560->127.0.0.1:43219: 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:54560->127.0.0.1:43219: i/o timeout) +_ smtp.go:141 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:47104->127.0.0.1:40173: 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:47104->127.0.0.1:40173: 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:47104->127.0.0.1:40173: i/o timeout) smtp_test.go:69: fakeServer exiting: EOF - testlib.go:26: test directory: "/tmp/testlib_428681394" + smtp_test.go:188: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:47104->127.0.0.1:40173: i/o timeout) + testlib.go:26: test directory: "/tmp/testlib_212334494" 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:188: failed as expected: MAIL+RCPT 501 mail error smtp_test.go:69: fakeServer exiting: EOF - testlib.go:26: test directory: "/tmp/testlib_413053620" + testlib.go:26: test directory: "/tmp/testlib_240201344" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" @@ -821,7 +856,7 @@ _ smtp.go:198 Courier.SMTP to@to: error: MAIL+RCPT 501 rcpt error smtp_test.go:188: failed as expected: MAIL+RCPT 501 rcpt error smtp_test.go:69: fakeServer exiting: EOF - testlib.go:26: test directory: "/tmp/testlib_551318534" + testlib.go:26: test directory: "/tmp/testlib_878457266" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" @@ -830,7 +865,7 @@ _ smtp.go:203 Courier.SMTP to@to: error: DATA 554 data error smtp_test.go:188: failed as expected: DATA 554 data error smtp_test.go:79: fakeServer exiting: unexpected EOF - testlib.go:26: test directory: "/tmp/testlib_603677480" + testlib.go:26: test directory: "/tmp/testlib_414462900" smtp_test.go:62: fakeServer got connection smtp_test.go:73: fakeServer read: "EHLO hello" smtp_test.go:73: fakeServer read: "MAIL FROM:" @@ -841,7 +876,7 @@ smtp_test.go:69: fakeServer exiting: EOF --- PASS: TestSMTPErrors (1.01s) === RUN TestNoMXServer - testlib.go:26: test directory: "/tmp/testlib_231377562" + testlib.go:26: test directory: "/tmp/testlib_261565702" _ 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) @@ -856,52 +891,52 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 1.166s +ok blitiri.com.ar/go/chasquid/internal/courier 1.120s === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_030776867" -_ domaininfo.go:107 DomainInfo /tmp/testlib_030776867: d1 incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_030776867: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_030776867: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE + testlib.go:26: test directory: "/tmp/testlib_221906276" +_ domaininfo.go:107 DomainInfo /tmp/testlib_221906276: d1 incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_221906276: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_221906276: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE --- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:26: test directory: "/tmp/testlib_537943368" -_ domaininfo.go:107 DomainInfo /tmp/testlib_537943368: insecure incoming level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_537943368: insecure outgoing level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:107 DomainInfo /tmp/testlib_537943368: secure incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_537943368: secure outgoing level raised: TLS_SECURE > PLAIN + testlib.go:26: test directory: "/tmp/testlib_924216861" +_ domaininfo.go:107 DomainInfo /tmp/testlib_924216861: insecure incoming level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_924216861: insecure outgoing level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:107 DomainInfo /tmp/testlib_924216861: secure incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_924216861: secure outgoing level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:26: test directory: "/tmp/testlib_974263694" -_ domaininfo.go:107 DomainInfo /tmp/testlib_974263694: pisis incoming level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_974263694: pisis outgoing level raised: TLS_INSECURE > PLAIN -_ domaininfo.go:107 DomainInfo /tmp/testlib_974263694: pisis incoming level raised: TLS_SECURE > TLS_INSECURE -_ domaininfo.go:139 DomainInfo /tmp/testlib_974263694: pisis outgoing level raised: TLS_SECURE > TLS_INSECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_974263694: error: pisis incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_974263694: error: pisis outgoing denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:107 DomainInfo /tmp/testlib_974263694: ssip incoming level raised: TLS_SECURE > PLAIN -_ domaininfo.go:139 DomainInfo /tmp/testlib_974263694: ssip outgoing level raised: TLS_SECURE > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_974263694: error: ssip incoming denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_974263694: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE -_ domaininfo.go:99 DomainInfo /tmp/testlib_974263694: error: ssip incoming denied: PLAIN < TLS_SECURE -_ domaininfo.go:131 DomainInfo /tmp/testlib_974263694: error: ssip outgoing denied: PLAIN < TLS_SECURE + testlib.go:26: test directory: "/tmp/testlib_326555003" +_ domaininfo.go:107 DomainInfo /tmp/testlib_326555003: pisis incoming level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_326555003: pisis outgoing level raised: TLS_INSECURE > PLAIN +_ domaininfo.go:107 DomainInfo /tmp/testlib_326555003: pisis incoming level raised: TLS_SECURE > TLS_INSECURE +_ domaininfo.go:139 DomainInfo /tmp/testlib_326555003: pisis outgoing level raised: TLS_SECURE > TLS_INSECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_326555003: error: pisis incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_326555003: error: pisis outgoing denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:107 DomainInfo /tmp/testlib_326555003: ssip incoming level raised: TLS_SECURE > PLAIN +_ domaininfo.go:139 DomainInfo /tmp/testlib_326555003: ssip outgoing level raised: TLS_SECURE > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_326555003: error: ssip incoming denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_326555003: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE +_ domaininfo.go:99 DomainInfo /tmp/testlib_326555003: error: ssip incoming denied: PLAIN < TLS_SECURE +_ domaininfo.go:131 DomainInfo /tmp/testlib_326555003: error: ssip outgoing denied: PLAIN < TLS_SECURE --- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:26: test directory: "/tmp/testlib_490202870" -_ domaininfo.go:107 DomainInfo /tmp/testlib_490202870: d1 incoming level raised: TLS_SECURE > PLAIN + testlib.go:26: test directory: "/tmp/testlib_616323715" +_ domaininfo.go:107 DomainInfo /tmp/testlib_616323715: d1 incoming level raised: TLS_SECURE > PLAIN --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.026s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.009s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:26: test directory: "/tmp/testlib_386696119" + testlib.go:26: test directory: "/tmp/testlib_573674577" --- 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.053s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.005s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -909,13 +944,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.042s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.005s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.032s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.012s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -940,7 +975,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.024s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.007s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -948,7 +983,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.030s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.011s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -958,41 +993,41 @@ === RUN TestDomainToUnicode --- PASS: TestDomainToUnicode (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.013s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.004s === RUN TestBin - testlib.go:26: test directory: "/tmp/testlib_735903694" + testlib.go:26: test directory: "/tmp/testlib_007590866" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:26: test directory: "/tmp/testlib_626231856" + testlib.go:26: test directory: "/tmp/testlib_844900052" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:26: test directory: "/tmp/testlib_475920610" + testlib.go:26: test directory: "/tmp/testlib_882537766" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:26: test directory: "/tmp/testlib_059817060" + testlib.go:26: test directory: "/tmp/testlib_779058504" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:26: test directory: "/tmp/testlib_699724061" + testlib.go:26: test directory: "/tmp/testlib_705718225" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.021s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.007s ? 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: Wed, 15 Mar 2023 04:27:33 +0000 + Message-ID: + Date: Wed, 09 Feb 2022 22:06:02 +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="FcNB2t8NXaY" + boundary="DedEUqJvWO0" - --FcNB2t8NXaY + --DedEUqJvWO0 Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1017,7 +1052,7 @@ oh! the humanity! - --FcNB2t8NXaY + --DedEUqJvWO0 Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1048,7 +1083,7 @@ - --FcNB2t8NXaY + --DedEUqJvWO0 Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1058,72 +1093,72 @@ Data ñaca. - --FcNB2t8NXaY-- + --DedEUqJvWO0-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_192060010" -_ queue.go:302 Queue.SendLoop kvzfEE7u5Fg: from from -_ queue.go:361 Queue.SendLoop kvzfEE7u5Fg: nodomain sent -_ queue.go:361 Queue.SendLoop kvzfEE7u5Fg: am@loco sent -_ queue.go:361 Queue.SendLoop kvzfEE7u5Fg: x@remote sent -_ queue.go:336 Queue.SendLoop kvzfEE7u5Fg: all done ---- PASS: TestBasic (0.00s) + testlib.go:26: test directory: "/tmp/testlib_360751584" +_ queue.go:302 Queue.SendLoop T0W_zBpoJVo: from from +_ queue.go:361 Queue.SendLoop T0W_zBpoJVo: nodomain sent +_ queue.go:361 Queue.SendLoop T0W_zBpoJVo: am@loco sent +_ queue.go:361 Queue.SendLoop T0W_zBpoJVo: x@remote sent +_ queue.go:336 Queue.SendLoop T0W_zBpoJVo: all done +--- PASS: TestBasic (0.02s) === RUN TestDSNOnTimeout - testlib.go:26: test directory: "/tmp/testlib_517019013" -_ queue.go:302 Queue.SendLoop gRuogrmsSa8: from from@loco -_ queue.go:459 Queue.SendLoop gRuogrmsSa8: queued DSN: GCAZ1lRnvSI -_ queue.go:336 Queue.SendLoop gRuogrmsSa8: all done -_ queue.go:302 Queue.SendLoop GCAZ1lRnvSI: from <> -_ queue.go:361 Queue.SendLoop GCAZ1lRnvSI: from@loco sent -_ queue.go:336 Queue.SendLoop GCAZ1lRnvSI: all done + testlib.go:26: test directory: "/tmp/testlib_691960675" +_ queue.go:302 Queue.SendLoop x_PC1C5fLjQ: from from@loco +_ queue.go:459 Queue.SendLoop x_PC1C5fLjQ: queued DSN: kcigPOslEMg +_ queue.go:336 Queue.SendLoop x_PC1C5fLjQ: all done +_ queue.go:302 Queue.SendLoop kcigPOslEMg: from <> +_ queue.go:361 Queue.SendLoop kcigPOslEMg: from@loco sent +_ queue.go:336 Queue.SendLoop kcigPOslEMg: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:26: test directory: "/tmp/testlib_569047689" -_ queue.go:302 Queue.SendLoop MJszXU9mH0U: from from -_ queue.go:361 Queue.SendLoop MJszXU9mH0U: ata@hualpa sent -_ queue.go:361 Queue.SendLoop MJszXU9mH0U: pq@loco sent -_ queue.go:361 Queue.SendLoop MJszXU9mH0U: rs@loco sent -_ queue.go:336 Queue.SendLoop MJszXU9mH0U: all done + testlib.go:26: test directory: "/tmp/testlib_032153927" +_ queue.go:302 Queue.SendLoop 0hcACsf8H5w: from from +_ queue.go:361 Queue.SendLoop 0hcACsf8H5w: ata@hualpa sent +_ queue.go:361 Queue.SendLoop 0hcACsf8H5w: pq@loco sent +_ queue.go:361 Queue.SendLoop 0hcACsf8H5w: rs@loco sent +_ queue.go:336 Queue.SendLoop 0hcACsf8H5w: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:26: test directory: "/tmp/testlib_998814920" + testlib.go:26: test directory: "/tmp/testlib_673537486" --- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:26: test directory: "/tmp/testlib_426292561" -_ queue.go:302 Queue.SendLoop g73hqevMxvM: from from -_ queue.go:361 Queue.SendLoop g73hqevMxvM: to sent -_ queue.go:369 Queue.SendLoop g73hqevMxvM: error: failed to write: open /tmp/testlib_998814920/.m:g73hqevMxvM092030588: no such file or directory -_ queue.go:336 Queue.SendLoop g73hqevMxvM: all done -E queue.go:224 failed to remove queue file "/tmp/testlib_998814920/m:g73hqevMxvM": remove /tmp/testlib_998814920/m:g73hqevMxvM: no such file or directory ---- PASS: TestPipes (0.01s) + testlib.go:26: test directory: "/tmp/testlib_005015503" +_ queue.go:302 Queue.SendLoop swYuyKtvfsE: from from +_ queue.go:361 Queue.SendLoop swYuyKtvfsE: to sent +_ queue.go:369 Queue.SendLoop swYuyKtvfsE: error: failed to write: open /tmp/testlib_673537486/.m:swYuyKtvfsE862112482: no such file or directory +_ queue.go:336 Queue.SendLoop swYuyKtvfsE: all done +E queue.go:224 failed to remove queue file "/tmp/testlib_673537486/m:swYuyKtvfsE": remove /tmp/testlib_673537486/m:swYuyKtvfsE: no such file or directory +--- PASS: TestPipes (0.00s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:26: test directory: "/tmp/testlib_672883627" -_ queue.go:302 Queue.SendLoop 5Jwe3u00d94: from from@loco -_ queue.go:361 Queue.SendLoop 5Jwe3u00d94: to@to sent -_ queue.go:336 Queue.SendLoop 5Jwe3u00d94: all done + testlib.go:26: test directory: "/tmp/testlib_924370137" +_ queue.go:302 Queue.SendLoop MIMa8ilicrM: from from@loco +_ queue.go:361 Queue.SendLoop MIMa8ilicrM: to@to sent +_ queue.go:336 Queue.SendLoop MIMa8ilicrM: all done --- PASS: TestSerialization (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.065s +ok blitiri.com.ar/go/chasquid/internal/queue 0.037s === RUN TestWriteFile - testlib.go:26: test directory: "/tmp/testlib_513731779" + testlib.go:26: test directory: "/tmp/testlib_875506551" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:26: test directory: "/tmp/testlib_903037607" + testlib.go:26: test directory: "/tmp/testlib_830468763" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:26: test directory: "/tmp/testlib_466148721" + testlib.go:26: test directory: "/tmp/testlib_413556613" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.039s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.008s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.034s +ok blitiri.com.ar/go/chasquid/internal/set 0.006s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1137,20 +1172,20 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.15s) +--- PASS: TestLineTooLong (0.02s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.198s -_ server.go:221 Server listening on 127.0.0.1:43957 (submission+TLS) -2023-03-15 04:27:34.596985 daemon listening on 127.0.0.1:43957 -_ server.go:221 Server listening on 127.0.0.1:35211 (SMTP) -2023-03-15 04:27:34.597078 daemon listening on 127.0.0.1:35211 -_ server.go:221 Server listening on 127.0.0.1:41837 (submission) -2023-03-15 04:27:34.597115 daemon listening on 127.0.0.1:41837 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.031s +_ server.go:221 Server listening on 127.0.0.1:41049 (SMTP) +2022-02-09 22:06:02.504930 daemon listening on 127.0.0.1:41049 +_ server.go:221 Server listening on 127.0.0.1:37879 (submission) +2022-02-09 22:06:02.505090 daemon listening on 127.0.0.1:37879 +_ server.go:221 Server listening on 127.0.0.1:41213 (submission+TLS) +2022-02-09 22:06:02.505130 daemon listening on 127.0.0.1:41213 +_ conn.go:191 SMTP.Conn 127.0.0.1:46436: error: error completing TLS handshake: EOF === RUN TestSecLevel - testlib.go:26: test directory: "/tmp/testlib_453018317" -_ conn.go:191 SMTP.Conn 127.0.0.1:60508: error: error completing TLS handshake: EOF -_ domaininfo.go:107 DomainInfo /tmp/testlib_453018317: slc incoming level raised: TLS_CLIENT > PLAIN -_ domaininfo.go:99 DomainInfo /tmp/testlib_453018317: error: slc incoming denied: PLAIN < TLS_CLIENT + testlib.go:26: test directory: "/tmp/testlib_221792617" +_ domaininfo.go:107 DomainInfo /tmp/testlib_221792617: slc incoming level raised: TLS_CLIENT > PLAIN +_ domaininfo.go:99 DomainInfo /tmp/testlib_221792617: 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 @@ -1187,162 +1222,159 @@ === RUN TestSanitizeEHLODomain --- PASS: TestSanitizeEHLODomain (0.00s) === RUN TestSimple -_ conn.go:664 SMTP.Conn 127.0.0.1:54010: Queued from from@from to [to@localhost] - qybNtMeaEfo -2023-03-15 04:27:34.708665 qybNtMeaEfo from=from@from queued ip=127.0.0.1:54010 to=[to@localhost] -_ queue.go:302 Queue.SendLoop qybNtMeaEfo: from from@from -_ queue.go:361 Queue.SendLoop qybNtMeaEfo: testuser@localhost sent -2023-03-15 04:27:34.708842 qybNtMeaEfo from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop qybNtMeaEfo: all done -2023-03-15 04:27:34.708992 qybNtMeaEfo from=from@from all done ---- PASS: TestSimple (0.01s) +_ conn.go:664 SMTP.Conn 127.0.0.1:35324: Queued from from@from to [to@localhost] - tfUqX65CU04 +2022-02-09 22:06:02.608452 tfUqX65CU04 from=from@from queued ip=127.0.0.1:35324 to=[to@localhost] +_ queue.go:302 Queue.SendLoop tfUqX65CU04: from from@from +_ queue.go:361 Queue.SendLoop tfUqX65CU04: testuser@localhost sent +2022-02-09 22:06:02.608642 tfUqX65CU04 from=from@from to=testuser@localhost sent +--- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ conn.go:664 SMTP.Conn 127.0.0.1:54012: Queued from from@from to [to@localhost] - V13WbzdjmGU -2023-03-15 04:27:34.716429 V13WbzdjmGU from=from@from queued ip=127.0.0.1:54012 to=[to@localhost] -_ queue.go:302 Queue.SendLoop V13WbzdjmGU: from from@from -_ queue.go:361 Queue.SendLoop V13WbzdjmGU: testuser@localhost sent -2023-03-15 04:27:34.716537 V13WbzdjmGU from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop V13WbzdjmGU: all done -2023-03-15 04:27:34.716662 V13WbzdjmGU from=from@from all done ---- PASS: TestSimpleTLS (0.01s) +_ queue.go:336 Queue.SendLoop tfUqX65CU04: all done +2022-02-09 22:06:02.608900 tfUqX65CU04 from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:35326: Queued from from@from to [to@localhost] - 4itDg1SFaNk +2022-02-09 22:06:02.611774 4itDg1SFaNk from=from@from queued ip=127.0.0.1:35326 to=[to@localhost] +_ queue.go:302 Queue.SendLoop 4itDg1SFaNk: from from@from +_ queue.go:361 Queue.SendLoop 4itDg1SFaNk: testuser@localhost sent +2022-02-09 22:06:02.611918 4itDg1SFaNk from=from@from to=testuser@localhost sent +--- PASS: TestSimpleTLS (0.00s) === RUN TestManyEmails -_ conn.go:664 SMTP.Conn 127.0.0.1:54014: Queued from from@from to [to@localhost] - FR-SzPAbwU8 -2023-03-15 04:27:34.723844 FR-SzPAbwU8 from=from@from queued ip=127.0.0.1:54014 to=[to@localhost] -_ queue.go:302 Queue.SendLoop FR-SzPAbwU8: from from@from -_ queue.go:361 Queue.SendLoop FR-SzPAbwU8: testuser@localhost sent -2023-03-15 04:27:34.723954 FR-SzPAbwU8 from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop FR-SzPAbwU8: all done -2023-03-15 04:27:34.724099 FR-SzPAbwU8 from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:54014: Queued from from@from to [to@localhost] - Q2_XW7SSXfQ -2023-03-15 04:27:34.724493 Q2_XW7SSXfQ from=from@from queued ip=127.0.0.1:54014 to=[to@localhost] -_ queue.go:302 Queue.SendLoop Q2_XW7SSXfQ: from from@from -_ queue.go:361 Queue.SendLoop Q2_XW7SSXfQ: testuser@localhost sent -2023-03-15 04:27:34.724582 Q2_XW7SSXfQ from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop Q2_XW7SSXfQ: all done -2023-03-15 04:27:34.724687 Q2_XW7SSXfQ from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:54014: Queued from from@from to [to@localhost] - i2JrNPwwA5g -2023-03-15 04:27:34.725071 i2JrNPwwA5g from=from@from queued ip=127.0.0.1:54014 to=[to@localhost] -_ queue.go:302 Queue.SendLoop i2JrNPwwA5g: from from@from -_ queue.go:361 Queue.SendLoop i2JrNPwwA5g: testuser@localhost sent -2023-03-15 04:27:34.725152 i2JrNPwwA5g from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop i2JrNPwwA5g: all done -2023-03-15 04:27:34.725254 i2JrNPwwA5g from=from@from all done +_ queue.go:336 Queue.SendLoop 4itDg1SFaNk: all done +2022-02-09 22:06:02.612066 4itDg1SFaNk from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:35328: Queued from from@from to [to@localhost] - PfaZIbKSBMY +2022-02-09 22:06:02.615505 PfaZIbKSBMY from=from@from queued ip=127.0.0.1:35328 to=[to@localhost] +_ queue.go:302 Queue.SendLoop PfaZIbKSBMY: from from@from +_ queue.go:361 Queue.SendLoop PfaZIbKSBMY: testuser@localhost sent +2022-02-09 22:06:02.615695 PfaZIbKSBMY from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop PfaZIbKSBMY: all done +2022-02-09 22:06:02.615885 PfaZIbKSBMY from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:35328: Queued from from@from to [to@localhost] - vNxLZew-Pzk +2022-02-09 22:06:02.616276 vNxLZew-Pzk from=from@from queued ip=127.0.0.1:35328 to=[to@localhost] +_ queue.go:302 Queue.SendLoop vNxLZew-Pzk: from from@from +_ queue.go:361 Queue.SendLoop vNxLZew-Pzk: testuser@localhost sent +2022-02-09 22:06:02.616386 vNxLZew-Pzk from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop vNxLZew-Pzk: all done +2022-02-09 22:06:02.616546 vNxLZew-Pzk from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:35328: Queued from from@from to [to@localhost] - x6lew4T_GvA +2022-02-09 22:06:02.616926 x6lew4T_GvA from=from@from queued ip=127.0.0.1:35328 to=[to@localhost] +_ queue.go:302 Queue.SendLoop x6lew4T_GvA: from from@from +_ queue.go:361 Queue.SendLoop x6lew4T_GvA: testuser@localhost sent +2022-02-09 22:06:02.617002 x6lew4T_GvA from=from@from to=testuser@localhost sent --- PASS: TestManyEmails (0.01s) === RUN TestAuth -2023-03-15 04:27:34.924826 127.0.0.1:36660 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:36660: Queued from testuser@localhost to [to@localhost] - ko69sZrb9VE -2023-03-15 04:27:34.927541 ko69sZrb9VE from=testuser@localhost queued ip=127.0.0.1:36660 to=[to@localhost] -_ queue.go:302 Queue.SendLoop ko69sZrb9VE: from testuser@localhost -_ queue.go:361 Queue.SendLoop ko69sZrb9VE: testuser@localhost sent -2023-03-15 04:27:34.927639 ko69sZrb9VE from=testuser@localhost to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop ko69sZrb9VE: all done -2023-03-15 04:27:34.927763 ko69sZrb9VE from=testuser@localhost all done ---- PASS: TestAuth (0.20s) +_ queue.go:336 Queue.SendLoop x6lew4T_GvA: all done +2022-02-09 22:06:02.617150 x6lew4T_GvA from=from@from all done +2022-02-09 22:06:02.722155 127.0.0.1:48940 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:48940: Queued from testuser@localhost to [to@localhost] - 5LJrT3_5NWU +2022-02-09 22:06:02.723081 5LJrT3_5NWU from=testuser@localhost queued ip=127.0.0.1:48940 to=[to@localhost] +_ queue.go:302 Queue.SendLoop 5LJrT3_5NWU: from testuser@localhost +_ queue.go:361 Queue.SendLoop 5LJrT3_5NWU: testuser@localhost sent +2022-02-09 22:06:02.723215 5LJrT3_5NWU from=testuser@localhost to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop 5LJrT3_5NWU: all done +2022-02-09 22:06:02.723368 5LJrT3_5NWU from=testuser@localhost all done +--- PASS: TestAuth (0.11s) === RUN TestSubmissionWithoutAuth -_ conn.go:292 SMTP.Conn 127.0.0.1:36662: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ conn.go:292 SMTP.Conn 127.0.0.1:48942: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.00s) === RUN TestAuthOnTLS -2023-03-15 04:27:35.161029 127.0.0.1:60510 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:60510: Queued from testuser@localhost to [to@localhost] - SlozK1lYiI8 -2023-03-15 04:27:35.163574 SlozK1lYiI8 from=testuser@localhost queued ip=127.0.0.1:60510 to=[to@localhost] -_ queue.go:302 Queue.SendLoop SlozK1lYiI8: from testuser@localhost -_ queue.go:361 Queue.SendLoop SlozK1lYiI8: testuser@localhost sent -2023-03-15 04:27:35.163675 SlozK1lYiI8 from=testuser@localhost to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop SlozK1lYiI8: all done -2023-03-15 04:27:35.163793 SlozK1lYiI8 from=testuser@localhost all done ---- PASS: TestAuthOnTLS (0.23s) +2022-02-09 22:06:02.829514 127.0.0.1:46448 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:46448: Queued from testuser@localhost to [to@localhost] - z4oAjXvC7fk +2022-02-09 22:06:02.830373 z4oAjXvC7fk from=testuser@localhost queued ip=127.0.0.1:46448 to=[to@localhost] +_ queue.go:302 Queue.SendLoop z4oAjXvC7fk: from testuser@localhost +_ queue.go:361 Queue.SendLoop z4oAjXvC7fk: testuser@localhost sent +2022-02-09 22:06:02.830513 z4oAjXvC7fk from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnTLS (0.10s) === RUN TestAuthOnSMTP -2023-03-15 04:27:35.337342 127.0.0.1:54016 auth succeeded for testuser@localhost -_ conn.go:664 SMTP.Conn 127.0.0.1:54016: Queued from testuser@localhost to [to@localhost] - M4XKVqje6e8 -2023-03-15 04:27:35.343350 M4XKVqje6e8 from=testuser@localhost queued ip=127.0.0.1:54016 to=[to@localhost] -_ queue.go:302 Queue.SendLoop M4XKVqje6e8: from testuser@localhost -_ queue.go:361 Queue.SendLoop M4XKVqje6e8: testuser@localhost sent -2023-03-15 04:27:35.343444 M4XKVqje6e8 from=testuser@localhost to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop M4XKVqje6e8: all done -2023-03-15 04:27:35.343562 M4XKVqje6e8 from=testuser@localhost all done ---- PASS: TestAuthOnSMTP (0.18s) +_ queue.go:336 Queue.SendLoop z4oAjXvC7fk: all done +2022-02-09 22:06:02.830671 z4oAjXvC7fk from=testuser@localhost all done +2022-02-09 22:06:02.933991 127.0.0.1:35336 auth succeeded for testuser@localhost +_ conn.go:664 SMTP.Conn 127.0.0.1:35336: Queued from testuser@localhost to [to@localhost] - 8j_qzCw862U +2022-02-09 22:06:02.935077 8j_qzCw862U from=testuser@localhost queued ip=127.0.0.1:35336 to=[to@localhost] +_ queue.go:302 Queue.SendLoop 8j_qzCw862U: from testuser@localhost +_ queue.go:361 Queue.SendLoop 8j_qzCw862U: testuser@localhost sent +2022-02-09 22:06:02.935201 8j_qzCw862U from=testuser@localhost to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop 8j_qzCw862U: all done +2022-02-09 22:06:02.935361 8j_qzCw862U from=testuser@localhost all done +--- PASS: TestAuthOnSMTP (0.10s) === RUN TestBrokenAuth -_ conn.go:1060 SMTP.Conn 127.0.0.1:36664: error: error authenticating \"user\"@\"broken\": failed to auth -2023-03-15 04:27:35.454970 127.0.0.1:36664 auth failed for user@broken -_ conn.go:292 SMTP.Conn 127.0.0.1:36664: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:292 SMTP.Conn 127.0.0.1:36664: error: unknown<*> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.11s) +_ conn.go:1060 SMTP.Conn 127.0.0.1:48948: error: error authenticating \"user\"@\"broken\": failed to auth +2022-02-09 22:06:03.039615 127.0.0.1:48948 auth failed for user@broken +_ conn.go:292 SMTP.Conn 127.0.0.1:48948: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:292 SMTP.Conn 127.0.0.1:48948: error: unknown<*> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.10s) === RUN TestWrongMailParsing -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:292 SMTP.Conn 127.0.0.1:54018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:54018: error: too many errors, breaking connection +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:292 SMTP.Conn 127.0.0.1:35340: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:35340: 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:54022: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:292 SMTP.Conn 127.0.0.1:35344: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption ---- PASS: TestRcptOption (0.01s) +--- PASS: TestRcptOption (0.00s) === RUN TestRelayForbidden -2023-03-15 04:27:35.472894 127.0.0.1:54026 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:292 SMTP.Conn 127.0.0.1:54026: error: RCPT failed: 503 5.7.1 Relay not allowed +2022-02-09 22:06:03.046010 127.0.0.1:35348 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:292 SMTP.Conn 127.0.0.1:35348: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2023-03-15 04:27:35.796837 127.0.0.1:36666 auth succeeded for testuser@localhost -_ conn.go:292 SMTP.Conn 127.0.0.1:36666: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.35s) +2022-02-09 22:06:03.150854 127.0.0.1:48960 auth succeeded for testuser@localhost +_ conn.go:292 SMTP.Conn 127.0.0.1:48960: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.11s) === RUN TestRcptFailsExistsCheck -_ conn.go:1098 SMTP.Conn 127.0.0.1:54028: error: error checking if user \"to@broken\" exists: failed to check if user exists -2023-03-15 04:27:35.834434 127.0.0.1:54028 rejected from=from@localhost to=[to@broken] - local user does not exist -_ conn.go:292 SMTP.Conn 127.0.0.1:54028: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) ---- PASS: TestRcptFailsExistsCheck (0.01s) +_ conn.go:1098 SMTP.Conn 127.0.0.1:35360: error: error checking if user \"to@broken\" exists: failed to check if user exists +2022-02-09 22:06:03.160552 127.0.0.1:35360 rejected from=from@localhost to=[to@broken] - local user does not exist +_ conn.go:292 SMTP.Conn 127.0.0.1:35360: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +--- PASS: TestRcptFailsExistsCheck (0.00s) === RUN TestTooMuchData -_ conn.go:664 SMTP.Conn 127.0.0.1:54030: Queued from from@from to [to@localhost] - WVq0mJtWuiU -2023-03-15 04:27:36.204599 WVq0mJtWuiU from=from@from queued ip=127.0.0.1:54030 to=[to@localhost] -_ queue.go:302 Queue.SendLoop WVq0mJtWuiU: from from@from -_ queue.go:361 Queue.SendLoop WVq0mJtWuiU: testuser@localhost sent -2023-03-15 04:27:36.204891 WVq0mJtWuiU from=from@from to=testuser@localhost sent -_ queue.go:336 Queue.SendLoop WVq0mJtWuiU: all done -2023-03-15 04:27:36.274776 WVq0mJtWuiU from=from@from all done -_ conn.go:664 SMTP.Conn 127.0.0.1:54030: Queued from from@from to [to@localhost] - DgPj0LX--e0 -_ queue.go:302 Queue.SendLoop DgPj0LX--e0: from from@from -_ queue.go:361 Queue.SendLoop DgPj0LX--e0: testuser@localhost sent -2023-03-15 04:27:36.570312 DgPj0LX--e0 from=from@from to=testuser@localhost sent -2023-03-15 04:27:36.579256 DgPj0LX--e0 from=from@from queued ip=127.0.0.1:54030 to=[to@localhost] -_ queue.go:336 Queue.SendLoop DgPj0LX--e0: all done -2023-03-15 04:27:36.635130 DgPj0LX--e0 from=from@from all done -_ conn.go:292 SMTP.Conn 127.0.0.1:54030: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:664 SMTP.Conn 127.0.0.1:54030: Queued from from@from to [to@localhost] - 2w4cYtULPME -2023-03-15 04:27:37.137097 2w4cYtULPME from=from@from queued ip=127.0.0.1:54030 to=[to@localhost] -_ queue.go:302 Queue.SendLoop 2w4cYtULPME: from from@from -_ queue.go:361 Queue.SendLoop 2w4cYtULPME: testuser@localhost sent -2023-03-15 04:27:37.137317 2w4cYtULPME from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (1.30s) +_ conn.go:664 SMTP.Conn 127.0.0.1:35362: Queued from from@from to [to@localhost] - Pt16P1ot8-I +2022-02-09 22:06:03.263904 Pt16P1ot8-I from=from@from queued ip=127.0.0.1:35362 to=[to@localhost] +_ queue.go:302 Queue.SendLoop Pt16P1ot8-I: from from@from +_ queue.go:361 Queue.SendLoop Pt16P1ot8-I: testuser@localhost sent +2022-02-09 22:06:03.264050 Pt16P1ot8-I from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop Pt16P1ot8-I: all done +2022-02-09 22:06:03.282930 Pt16P1ot8-I from=from@from all done +_ conn.go:664 SMTP.Conn 127.0.0.1:35362: Queued from from@from to [to@localhost] - 2Dbjm33SJEs +2022-02-09 22:06:03.380728 2Dbjm33SJEs from=from@from queued ip=127.0.0.1:35362 to=[to@localhost] +_ queue.go:302 Queue.SendLoop 2Dbjm33SJEs: from from@from +_ queue.go:361 Queue.SendLoop 2Dbjm33SJEs: testuser@localhost sent +2022-02-09 22:06:03.380947 2Dbjm33SJEs from=from@from to=testuser@localhost sent +_ queue.go:336 Queue.SendLoop 2Dbjm33SJEs: all done +2022-02-09 22:06:03.405364 2Dbjm33SJEs from=from@from all done +_ conn.go:292 SMTP.Conn 127.0.0.1:35362: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:664 SMTP.Conn 127.0.0.1:35362: Queued from from@from to [to@localhost] - Lz6_M5cZoVM +2022-02-09 22:06:03.596663 Lz6_M5cZoVM from=from@from queued ip=127.0.0.1:35362 to=[to@localhost] +_ queue.go:302 Queue.SendLoop Lz6_M5cZoVM: from from@from +_ queue.go:361 Queue.SendLoop Lz6_M5cZoVM: testuser@localhost sent +2022-02-09 22:06:03.596870 Lz6_M5cZoVM from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.44s) === RUN TestSimpleCommands -_ conn.go:292 SMTP.Conn 127.0.0.1:54032: 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:54032: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:292 SMTP.Conn 127.0.0.1:35364: 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:35364: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. --- PASS: TestSimpleCommands (0.00s) === RUN TestLongLines -_ conn.go:292 SMTP.Conn 127.0.0.1:54034: error: unknown<> failed: 500 5.5.1 Unknown command -_ conn.go:314 SMTP.Conn 127.0.0.1:54034: error: exiting with error: line too long ---- PASS: TestLongLines (0.02s) +_ conn.go:292 SMTP.Conn 127.0.0.1:35366: error: unknown<> failed: 500 5.5.1 Unknown command +_ conn.go:314 SMTP.Conn 127.0.0.1:35366: 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:54038: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestRepeatedStartTLS (0.03s) +_ conn.go:292 SMTP.Conn 127.0.0.1:35370: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestRepeatedStartTLS (0.00s) === RUN TestStartTLSOnTLS -_ conn.go:292 SMTP.Conn 127.0.0.1:60512: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:292 SMTP.Conn 127.0.0.1:46486: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestStartTLSOnTLS (0.00s) PASS -_ queue.go:336 Queue.SendLoop 2w4cYtULPME: all done -2023-03-15 04:27:37.190440 2w4cYtULPME from=from@from all done -E queue.go:224 failed to remove queue file "/tmp/chasquid_test:594968486/queue/m:2w4cYtULPME": remove /tmp/chasquid_test:594968486/queue/m:2w4cYtULPME: no such file or directory -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.828s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.238s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -1357,19 +1389,19 @@ sts_test.go:210: policy404: got error as expected: HTTP response status code: 404 sts_test.go:218: version99: got expected error: unknown policy version sts_test.go:226: domErr: got expected error: error for testing purposes ---- PASS: TestFetch (0.01s) +--- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.14s) +--- PASS: TestPolicyTooBig (0.02s) === RUN TestCacheBasics - testlib.go:26: test directory: "/tmp/testlib_392988543" + testlib.go:26: test directory: "/tmp/testlib_355686566" 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.00s) === RUN TestCacheBadData - testlib.go:26: test directory: "/tmp/testlib_628738516" + testlib.go:26: test directory: "/tmp/testlib_115135367" 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 +1410,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_104008967" + testlib.go:26: test directory: "/tmp/testlib_430378254" 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_124099068" + testlib.go:26: test directory: "/tmp/testlib_900943887" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1392,11 +1424,11 @@ === RUN TestHasSTSRecord --- PASS: TestHasSTSRecord (0.00s) === RUN TestHTTPGet ---- PASS: TestHTTPGet (0.01s) +--- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.200s +ok blitiri.com.ar/go/chasquid/internal/sts 0.039s === RUN TestBasic - testlib.go:26: test directory: "/tmp/testlib_920237988" + testlib.go:26: test directory: "/tmp/testlib_891654182" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:34: recovered: invalid/dangerous directory @@ -1407,47 +1439,47 @@ testlib_test.go:61: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:26: test directory: "/tmp/testlib_434131062" + testlib.go:26: test directory: "/tmp/testlib_583577160" --- 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.051s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.030s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.066s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.009s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestEmptyLoad - userdb_test.go:38: file: "/tmp/userdb_test432662723" - userdb_test.go:38: file: "/tmp/userdb_test111679814" + userdb_test.go:38: file: "/tmp/userdb_test293424055" + userdb_test.go:38: file: "/tmp/userdb_test893053866" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:38: file: "/tmp/userdb_test290331629" ---- PASS: TestWrite (1.16s) + userdb_test.go:38: file: "/tmp/userdb_test678726401" +--- PASS: TestWrite (0.63s) === RUN TestNew ---- PASS: TestNew (0.23s) +--- PASS: TestNew (0.08s) === RUN TestInvalidUsername - userdb_test.go:38: file: "/tmp/userdb_test684105585" + userdb_test.go:38: file: "/tmp/userdb_test901395397" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:38: file: "/tmp/userdb_test902990364" + userdb_test.go:38: file: "/tmp/userdb_test901845600" --- PASS: TestPlainScheme (0.00s) === RUN TestReload - userdb_test.go:38: file: "/tmp/userdb_test557577646" + userdb_test.go:38: file: "/tmp/userdb_test412052626" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:38: file: "/tmp/userdb_test001653045" ---- PASS: TestRemoveUser (0.21s) + userdb_test.go:38: file: "/tmp/userdb_test202298569" +--- PASS: TestRemoveUser (0.08s) === RUN TestExists - userdb_test.go:38: file: "/tmp/userdb_test309096720" ---- PASS: TestExists (0.26s) + userdb_test.go:38: file: "/tmp/userdb_test682529172" +--- PASS: TestExists (0.08s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.895s +ok blitiri.com.ar/go/chasquid/internal/userdb 0.871s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1493,8 +1525,8 @@ dh_missing -O--buildsystem=golang dh_strip -a -O--buildsystem=golang dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid -dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid-util dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/smtp-check +dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid-util dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/mda-lmtp dh_makeshlibs -a -O--buildsystem=golang dh_shlibdeps -a -O--buildsystem=golang @@ -1511,12 +1543,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/4151574/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/4151574/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/2418705 and its subdirectories -I: Current time: Tue Mar 14 16:28:16 -12 2023 -I: pbuilder-time-stamp: 1678854496 +I: removing directory /srv/workspace/pbuilder/4151574 and its subdirectories +I: Current time: Thu Feb 10 12:06:26 +14 2022 +I: pbuilder-time-stamp: 1644444386