Diff of the two buildlogs: -- --- b1/build.log 2023-05-23 23:07:53.847263282 +0000 +++ b2/build.log 2023-05-23 23:17:00.055314408 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue May 23 10:55:19 -12 2023 -I: pbuilder-time-stamp: 1684882519 +I: Current time: Wed May 24 13:09:51 +14 2023 +I: pbuilder-time-stamp: 1684883391 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./chasquid_1.11.orig.tar.xz] I: copying [./chasquid_1.11-2.debian.tar.xz] I: Extracting source -gpgv: Signature made Mon Feb 27 01:23:55 2023 -12 +gpgv: Signature made Tue Feb 28 03:23:55 2023 +14 gpgv: using EDDSA key A095B66EE09024BEE6A2F0722A27904BD7243EDA gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./chasquid_1.11-2.dsc: no acceptable signature found @@ -27,135 +27,167 @@ dpkg-source: info: applying 0001-Use_chasquid_user.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/14427/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/9284/tmp/hooks/D01_modify_environment starting +debug: Running on jtx1c. +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 +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 May 24 13:10 /bin/sh -> /bin/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/9284/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/9284/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='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='bookworm' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="15" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='5022eebb5e8a4188840623759191ff63' - 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='14427' - PS1='# ' - PS2='> ' + INVOCATION_ID=9b9a594812c24474891a5e6971df38ae + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + 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=9284 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/r-b-build.OUsMiaiK/pbuilderrc_xFno --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.OUsMiaiK/b1 --logfile b1/build.log chasquid_1.11-2.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + 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/r-b-build.OUsMiaiK/pbuilderrc_oZx2 --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.OUsMiaiK/b2 --logfile b2/build.log --extrapackages usrmerge chasquid_1.11-2.dsc' + SUDO_GID=114 + SUDO_UID=108 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux cbxi4pro0 5.10.0-23-armmp #1 SMP Debian 5.10.179-1 (2023-05-12) armv7l GNU/Linux + Linux i-capture-the-hostname 5.10.0-23-arm64 #1 SMP Debian 5.10.179-1 (2023-05-12) aarch64 GNU/Linux I: ls -l /bin total 5072 - -rwxr-xr-x 1 root root 838488 Apr 23 09:24 bash - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bunzip2 - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bzcat - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Sep 18 2022 bzdiff - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4893 Nov 27 2021 bzexe - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Sep 18 2022 bzgrep - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bzip2 - -rwxr-xr-x 1 root root 67112 Sep 18 2022 bzip2recover - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Sep 18 2022 bzmore - -rwxr-xr-x 1 root root 67632 Sep 20 2022 cat - -rwxr-xr-x 1 root root 67676 Sep 20 2022 chgrp - -rwxr-xr-x 1 root root 67644 Sep 20 2022 chmod - -rwxr-xr-x 1 root root 67684 Sep 20 2022 chown - -rwxr-xr-x 1 root root 133532 Sep 20 2022 cp - -rwxr-xr-x 1 root root 132868 Jan 5 01:20 dash - -rwxr-xr-x 1 root root 133220 Sep 20 2022 date - -rwxr-xr-x 1 root root 67732 Sep 20 2022 dd - -rwxr-xr-x 1 root root 68104 Sep 20 2022 df - -rwxr-xr-x 1 root root 133632 Sep 20 2022 dir - -rwxr-xr-x 1 root root 59128 Mar 22 21:02 dmesg - lrwxrwxrwx 1 root root 8 Dec 19 01:33 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Dec 19 01:33 domainname -> hostname - -rwxr-xr-x 1 root root 67560 Sep 20 2022 echo - -rwxr-xr-x 1 root root 41 Jan 24 02:43 egrep - -rwxr-xr-x 1 root root 67548 Sep 20 2022 false - -rwxr-xr-x 1 root root 41 Jan 24 02:43 fgrep - -rwxr-xr-x 1 root root 55748 Mar 22 21:02 findmnt - -rwsr-xr-x 1 root root 26208 Mar 22 20:15 fusermount - -rwxr-xr-x 1 root root 128608 Jan 24 02:43 grep - -rwxr-xr-x 2 root root 2346 Apr 9 2022 gunzip - -rwxr-xr-x 1 root root 6447 Apr 9 2022 gzexe - -rwxr-xr-x 1 root root 64220 Apr 9 2022 gzip - -rwxr-xr-x 1 root root 67032 Dec 19 01:33 hostname - -rwxr-xr-x 1 root root 67720 Sep 20 2022 ln - -rwxr-xr-x 1 root root 35132 Mar 22 21:51 login - -rwxr-xr-x 1 root root 133632 Sep 20 2022 ls - -rwxr-xr-x 1 root root 136808 Mar 22 21:02 lsblk - -rwxr-xr-x 1 root root 67800 Sep 20 2022 mkdir - -rwxr-xr-x 1 root root 67764 Sep 20 2022 mknod - -rwxr-xr-x 1 root root 67596 Sep 20 2022 mktemp - -rwxr-xr-x 1 root root 38504 Mar 22 21:02 more - -rwsr-xr-x 1 root root 38496 Mar 22 21:02 mount - -rwxr-xr-x 1 root root 9824 Mar 22 21:02 mountpoint - -rwxr-xr-x 1 root root 133532 Sep 20 2022 mv - lrwxrwxrwx 1 root root 8 Dec 19 01:33 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 2 18:25 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 67608 Sep 20 2022 pwd - lrwxrwxrwx 1 root root 4 Apr 23 09:24 rbash -> bash - -rwxr-xr-x 1 root root 67600 Sep 20 2022 readlink - -rwxr-xr-x 1 root root 67672 Sep 20 2022 rm - -rwxr-xr-x 1 root root 67600 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 67400 Nov 2 2022 run-parts - -rwxr-xr-x 1 root root 133372 Jan 5 07:55 sed - lrwxrwxrwx 1 root root 4 Jan 5 01:20 sh -> dash - -rwxr-xr-x 1 root root 67584 Sep 20 2022 sleep - -rwxr-xr-x 1 root root 67644 Sep 20 2022 stty - -rwsr-xr-x 1 root root 50800 Mar 22 21:02 su - -rwxr-xr-x 1 root root 67584 Sep 20 2022 sync - -rwxr-xr-x 1 root root 336764 Apr 6 02:25 tar - -rwxr-xr-x 1 root root 67144 Nov 2 2022 tempfile - -rwxr-xr-x 1 root root 133224 Sep 20 2022 touch - -rwxr-xr-x 1 root root 67548 Sep 20 2022 true - -rwxr-xr-x 1 root root 9768 Mar 22 20:15 ulockmgr_server - -rwsr-xr-x 1 root root 22108 Mar 22 21:02 umount - -rwxr-xr-x 1 root root 67572 Sep 20 2022 uname - -rwxr-xr-x 2 root root 2346 Apr 9 2022 uncompress - -rwxr-xr-x 1 root root 133632 Sep 20 2022 vdir - -rwxr-xr-x 1 root root 42608 Mar 22 21:02 wdctl - lrwxrwxrwx 1 root root 8 Dec 19 01:33 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Apr 9 2022 zcat - -rwxr-xr-x 1 root root 1678 Apr 9 2022 zcmp - -rwxr-xr-x 1 root root 6460 Apr 9 2022 zdiff - -rwxr-xr-x 1 root root 29 Apr 9 2022 zegrep - -rwxr-xr-x 1 root root 29 Apr 9 2022 zfgrep - -rwxr-xr-x 1 root root 2081 Apr 9 2022 zforce - -rwxr-xr-x 1 root root 8103 Apr 9 2022 zgrep - -rwxr-xr-x 1 root root 2206 Apr 9 2022 zless - -rwxr-xr-x 1 root root 1842 Apr 9 2022 zmore - -rwxr-xr-x 1 root root 4577 Apr 9 2022 znew -I: user script /srv/workspace/pbuilder/14427/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 838488 Apr 24 11:24 bash + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bunzip2 + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bzcat + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Sep 19 2022 bzdiff + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4893 Nov 28 2021 bzexe + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Sep 19 2022 bzgrep + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bzip2 + -rwxr-xr-x 1 root root 67112 Sep 19 2022 bzip2recover + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Sep 19 2022 bzmore + -rwxr-xr-x 1 root root 67632 Sep 21 2022 cat + -rwxr-xr-x 1 root root 67676 Sep 21 2022 chgrp + -rwxr-xr-x 1 root root 67644 Sep 21 2022 chmod + -rwxr-xr-x 1 root root 67684 Sep 21 2022 chown + -rwxr-xr-x 1 root root 133532 Sep 21 2022 cp + -rwxr-xr-x 1 root root 132868 Jan 6 03:20 dash + -rwxr-xr-x 1 root root 133220 Sep 21 2022 date + -rwxr-xr-x 1 root root 67732 Sep 21 2022 dd + -rwxr-xr-x 1 root root 68104 Sep 21 2022 df + -rwxr-xr-x 1 root root 133632 Sep 21 2022 dir + -rwxr-xr-x 1 root root 59128 Mar 23 23:02 dmesg + lrwxrwxrwx 1 root root 8 Dec 20 03:33 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Dec 20 03:33 domainname -> hostname + -rwxr-xr-x 1 root root 67560 Sep 21 2022 echo + -rwxr-xr-x 1 root root 41 Jan 25 04:43 egrep + -rwxr-xr-x 1 root root 67548 Sep 21 2022 false + -rwxr-xr-x 1 root root 41 Jan 25 04:43 fgrep + -rwxr-xr-x 1 root root 55748 Mar 23 23:02 findmnt + -rwsr-xr-x 1 root root 26208 Mar 23 22:15 fusermount + -rwxr-xr-x 1 root root 128608 Jan 25 04:43 grep + -rwxr-xr-x 2 root root 2346 Apr 10 2022 gunzip + -rwxr-xr-x 1 root root 6447 Apr 10 2022 gzexe + -rwxr-xr-x 1 root root 64220 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 67032 Dec 20 03:33 hostname + -rwxr-xr-x 1 root root 67720 Sep 21 2022 ln + -rwxr-xr-x 1 root root 35132 Mar 23 23:51 login + -rwxr-xr-x 1 root root 133632 Sep 21 2022 ls + -rwxr-xr-x 1 root root 136808 Mar 23 23:02 lsblk + -rwxr-xr-x 1 root root 67800 Sep 21 2022 mkdir + -rwxr-xr-x 1 root root 67764 Sep 21 2022 mknod + -rwxr-xr-x 1 root root 67596 Sep 21 2022 mktemp + -rwxr-xr-x 1 root root 38504 Mar 23 23:02 more + -rwsr-xr-x 1 root root 38496 Mar 23 23:02 mount + -rwxr-xr-x 1 root root 9824 Mar 23 23:02 mountpoint + -rwxr-xr-x 1 root root 133532 Sep 21 2022 mv + lrwxrwxrwx 1 root root 8 Dec 20 03:33 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 3 20:25 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 67608 Sep 21 2022 pwd + lrwxrwxrwx 1 root root 4 Apr 24 11:24 rbash -> bash + -rwxr-xr-x 1 root root 67600 Sep 21 2022 readlink + -rwxr-xr-x 1 root root 67672 Sep 21 2022 rm + -rwxr-xr-x 1 root root 67600 Sep 21 2022 rmdir + -rwxr-xr-x 1 root root 67400 Nov 3 2022 run-parts + -rwxr-xr-x 1 root root 133372 Jan 6 09:55 sed + lrwxrwxrwx 1 root root 9 May 24 13:10 sh -> /bin/bash + -rwxr-xr-x 1 root root 67584 Sep 21 2022 sleep + -rwxr-xr-x 1 root root 67644 Sep 21 2022 stty + -rwsr-xr-x 1 root root 50800 Mar 23 23:02 su + -rwxr-xr-x 1 root root 67584 Sep 21 2022 sync + -rwxr-xr-x 1 root root 336764 Apr 7 04:25 tar + -rwxr-xr-x 1 root root 67144 Nov 3 2022 tempfile + -rwxr-xr-x 1 root root 133224 Sep 21 2022 touch + -rwxr-xr-x 1 root root 67548 Sep 21 2022 true + -rwxr-xr-x 1 root root 9768 Mar 23 22:15 ulockmgr_server + -rwsr-xr-x 1 root root 22108 Mar 23 23:02 umount + -rwxr-xr-x 1 root root 67572 Sep 21 2022 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 133632 Sep 21 2022 vdir + -rwxr-xr-x 1 root root 42608 Mar 23 23:02 wdctl + lrwxrwxrwx 1 root root 8 Dec 20 03:33 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Apr 10 2022 zcat + -rwxr-xr-x 1 root root 1678 Apr 10 2022 zcmp + -rwxr-xr-x 1 root root 6460 Apr 10 2022 zdiff + -rwxr-xr-x 1 root root 29 Apr 10 2022 zegrep + -rwxr-xr-x 1 root root 29 Apr 10 2022 zfgrep + -rwxr-xr-x 1 root root 2081 Apr 10 2022 zforce + -rwxr-xr-x 1 root root 8103 Apr 10 2022 zgrep + -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless + -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore + -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew +I: user script /srv/workspace/pbuilder/9284/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -259,7 +291,7 @@ Get: 44 http://deb.debian.org/debian bookworm/main armhf golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 45 http://deb.debian.org/debian bookworm/main armhf golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 46 http://deb.debian.org/debian bookworm/main armhf golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 100 MB in 6s (15.7 MB/s) +Fetched 100 MB in 12s (8272 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (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 ... 19329 files and directories currently installed.) @@ -457,8 +489,19 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/chasquid-1.11/ && 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.11-2_source.changes +I: user script /srv/workspace/pbuilder/9284/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +removed '/etc/unsupported-skip-usrmerge-conversion' +The system has been successfully converted. +I: user script /srv/workspace/pbuilder/9284/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/chasquid-1.11/ && 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.11-2_source.changes dpkg-buildpackage: info: source package chasquid dpkg-buildpackage: info: source version 1.11-2 dpkg-buildpackage: info: source distribution unstable @@ -478,10 +521,10 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/chasquid-1.11' dh_auto_build -- -ldflags " -X main.version=1.11-2 -X main.sourceDateTs=1677503939" - cd obj-arm-linux-gnueabihf && go install -trimpath -v -p 3 -ldflags " -X main.version=1.11-2 -X main.sourceDateTs=1677503939" 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/nettrace 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 -encoding + cd obj-arm-linux-gnueabihf && go install -trimpath -v -p 4 -ldflags " -X main.version=1.11-2 -X main.sourceDateTs=1677503939" 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/nettrace 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/goarch +encoding +internal/unsafeheader internal/cpu internal/abi internal/goexperiment @@ -489,16 +532,16 @@ runtime/internal/math runtime/internal/sys runtime/internal/syscall -internal/bytealg -runtime/internal/atomic internal/itoa math/bits +internal/bytealg unicode/utf8 +runtime/internal/atomic math internal/race sync/atomic -runtime unicode +runtime internal/nettrace google.golang.org/protobuf/internal/flags unicode/utf16 @@ -507,54 +550,71 @@ container/list crypto/internal/subtle crypto/subtle -crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 +crypto/internal/boring/sig vendor/golang.org/x/crypto/internal/subtle blitiri.com.ar/go/chasquid/internal/set -internal/reflectlite sync -internal/testlog +internal/reflectlite internal/singleflight -google.golang.org/protobuf/internal/pragma +internal/testlog runtime/cgo +google.golang.org/protobuf/internal/pragma math/rand +internal/sysinfo errors sort -strconv io +strconv internal/oserror syscall -reflect -internal/syscall/unix -time internal/safefilepath -internal/syscall/execenv path strings +reflect vendor/golang.org/x/net/dns/dnsmessage -internal/poll -internal/fmtsort -io/fs -context -encoding/binary -os +internal/syscall/unix +time +internal/syscall/execenv hash hash/fnv bytes hash/crc32 -encoding/base64 -embed +html bufio +crypto +crypto/internal/randutil +crypto/internal/nistec/fiat +crypto/rc4 +vendor/golang.org/x/text/transform +net/http/internal/ascii +internal/poll +io/fs +context +embed +internal/fmtsort +os +encoding/binary +golang.org/x/text/transform +golang.org/x/text/internal/tag +golang.org/x/text/runes +encoding/base64 +crypto/cipher +crypto/internal/edwards25519/field fmt internal/godebug +path/filepath internal/intern net/netip -path/filepath google.golang.org/protobuf/internal/detrand io/ioutil blitiri.com.ar/go/chasquid/internal/safeio +crypto/internal/boring +crypto/aes net -html +crypto/des +crypto/internal/nistec +crypto/sha512 flag log google.golang.org/protobuf/internal/errors @@ -562,8 +622,10 @@ google.golang.org/protobuf/encoding/protowire compress/flate google.golang.org/protobuf/reflect/protoreflect -compress/gzip encoding/json +compress/gzip +google.golang.org/protobuf/internal/version +net/url google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/encoding/text @@ -572,45 +634,29 @@ google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt -google.golang.org/protobuf/proto google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/encoding/defval -google.golang.org/protobuf/internal/version -net/url +google.golang.org/protobuf/proto text/template/parse +math/big google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc blitiri.com.ar/go/chasquid/internal/protoio text/template +crypto/rand +crypto/elliptic +html/template log/syslog -blitiri.com.ar/go/log google.golang.org/protobuf/internal/encoding/tag -crypto -html/template -google.golang.org/protobuf/internal/impl -crypto/cipher -crypto/internal/boring -crypto/internal/randutil -math/big -crypto/aes -crypto/des -crypto/internal/nistec/fiat -crypto/rand crypto/internal/boring/bbig -crypto/sha512 encoding/asn1 -vendor/golang.org/x/crypto/cryptobyte -crypto/internal/edwards25519/field +blitiri.com.ar/go/log +google.golang.org/protobuf/internal/impl crypto/internal/edwards25519 -crypto/internal/nistec -google.golang.org/protobuf/internal/filetype +vendor/golang.org/x/crypto/cryptobyte crypto/ed25519 -google.golang.org/protobuf/runtime/protoimpl crypto/hmac -crypto/elliptic crypto/md5 -blitiri.com.ar/go/chasquid/internal/config -crypto/rc4 crypto/rsa crypto/sha1 crypto/ecdsa @@ -618,15 +664,14 @@ crypto/dsa encoding/hex encoding/pem -crypto/x509/pkix vendor/golang.org/x/crypto/chacha20 +crypto/x509/pkix vendor/golang.org/x/crypto/internal/poly1305 crypto/x509 vendor/golang.org/x/crypto/curve25519/internal/field vendor/golang.org/x/crypto/chacha20poly1305 -vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/curve25519 -vendor/golang.org/x/text/transform +vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/text/secure/bidirule @@ -638,74 +683,72 @@ mime/quotedprintable mime/multipart net/http/internal -net/http/internal/ascii +google.golang.org/protobuf/internal/filetype vendor/golang.org/x/net/http/httpguts vendor/golang.org/x/net/http/httpproxy blitiri.com.ar/go/chasquid/internal/envelope -golang.org/x/text/transform golang.org/x/text/unicode/bidi +google.golang.org/protobuf/runtime/protoimpl golang.org/x/text/unicode/norm -golang.org/x/text/secure/bidirule +blitiri.com.ar/go/chasquid/internal/config os/exec +golang.org/x/text/secure/bidirule blitiri.com.ar/go/chasquid/internal/dovecot -golang.org/x/text/internal/tag -net/http/httptrace -net/smtp -golang.org/x/net/idna -net/http golang.org/x/text/internal/language -blitiri.com.ar/go/chasquid/internal/smtp -golang.org/x/text/runes -golang.org/x/text/internal/language/compact golang.org/x/text/width -golang.org/x/text/language blitiri.com.ar/go/chasquid/internal/haproxy net/mail -golang.org/x/text/internal -golang.org/x/text/cases +golang.org/x/net/idna blitiri.com.ar/go/chasquid/internal/tlsconst golang.org/x/crypto/pbkdf2 +net/http/httptrace +net/smtp +golang.org/x/text/internal/language/compact +net/http golang.org/x/crypto/scrypt +golang.org/x/text/language regexp/syntax -golang.org/x/text/secure/precis -regexp -blitiri.com.ar/go/spf -blitiri.com.ar/go/chasquid/internal/normalize -blitiri.com.ar/go/chasquid/internal/userdb +blitiri.com.ar/go/chasquid/internal/smtp blitiri.com.ar/go/systemd -internal/profile text/tabwriter runtime/pprof +golang.org/x/text/internal +golang.org/x/text/cases +regexp runtime/trace os/signal runtime/debug +golang.org/x/text/secure/precis +blitiri.com.ar/go/spf +internal/profile golang.org/x/sys/unix +blitiri.com.ar/go/chasquid/internal/normalize +blitiri.com.ar/go/chasquid/internal/userdb blitiri.com.ar/go/chasquid/cmd/mda-lmtp +blitiri.com.ar/go/chasquid/internal/protoio/testpb +testing +blitiri.com.ar/go/chasquid/internal/testlib +golang.org/x/term blitiri.com.ar/go/chasquid/internal/nettrace -blitiri.com.ar/go/chasquid/internal/trace -blitiri.com.ar/go/chasquid/internal/domaininfo +golang.org/x/net/context/ctxhttp expvar +net/http/pprof blitiri.com.ar/go/chasquid/internal/expvarom -golang.org/x/net/context/ctxhttp +blitiri.com.ar/go/chasquid/internal/trace blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/courier blitiri.com.ar/go/chasquid/internal/aliases +blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/auth -blitiri.com.ar/go/chasquid/internal/queue -net/http/pprof -golang.org/x/term blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/cmd/chasquid-util +blitiri.com.ar/go/chasquid/internal/courier +blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid -blitiri.com.ar/go/chasquid/internal/protoio/testpb -internal/sysinfo -testing -blitiri.com.ar/go/chasquid/internal/testlib make[1]: Leaving directory '/build/chasquid-1.11' dh_auto_test -O--buildsystem=golang - cd obj-arm-linux-gnueabihf && go test -vet=off -v -p 3 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/nettrace 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-arm-linux-gnueabihf && go test -vet=off -v -p 4 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/nettrace 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] @@ -717,24 +760,24 @@ === RUN TestUserLookupErrors --- PASS: TestUserLookupErrors (0.00s) === RUN TestAddrRewrite ---- PASS: TestAddrRewrite (0.01s) +--- PASS: TestAddrRewrite (0.00s) === RUN TestExistsRewrite --- PASS: TestExistsRewrite (0.00s) === RUN TestTooMuchRecursion --- PASS: TestTooMuchRecursion (0.00s) === RUN TestTooMuchRecursionOnCatchAll ---- PASS: TestTooMuchRecursionOnCatchAll (0.01s) +--- PASS: TestTooMuchRecursionOnCatchAll (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.03s) +--- PASS: TestAddFile (0.02s) === RUN TestRichFile ---- PASS: TestRichFile (0.01s) +--- PASS: TestRichFile (0.00s) === RUN TestManyFiles ---- PASS: TestManyFiles (0.02s) +--- PASS: TestManyFiles (0.00s) === RUN TestHookError _ aliases.go:501 Hook.Alias-Resolve a@localA: error: exit status 1 _ aliases.go:501 Hook.Alias-Resolve *@localA: error: exit status 1 _ aliases.go:501 Hook.Alias-Resolve a@localA: error: exit status 1 ---- PASS: TestHookError (0.06s) +--- PASS: TestHookError (0.09s) === RUN FuzzReader === RUN FuzzReader/1c24d2215db69748c6fd16797673ad11ebc7e6167fe1bc1f54c6959ec10407b6 === RUN FuzzReader/4adaceaa32e2b32c00322948769d62c2dd42e1d9f4950d3c5b411c710e6d4a86 @@ -748,7 +791,7 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.452s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.295s === RUN TestDecodeResponse auth_test.go:56: OK: "" failed with response pieces != 3, as per RFC auth_test.go:56: OK: "\x00" failed with response pieces != 3, as per RFC @@ -759,13 +802,13 @@ auth_test.go:56: OK: "a\x00b\x00c" failed with auth IDs do not match auth_test.go:56: OK: "a@a\x00b@b\x00pass" failed with auth IDs do not match auth_test.go:56: OK: "\xffa@b\x00\xffa@b\x00pass" failed with bidirule: failed Bidi Rule ---- PASS: TestDecodeResponse (0.01s) +--- PASS: TestDecodeResponse (0.00s) === RUN TestAuthenticate ---- PASS: TestAuthenticate (2.17s) +--- PASS: TestAuthenticate (1.65s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.19s) +--- PASS: TestMultipleBackends (0.20s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload @@ -782,7 +825,7 @@ === RUN FuzzDecodeResponse/c2ae184876dd0fe9acfc8a5e2f2174a968b889b01e0f5c9a61fa27d7361f0091 === RUN FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 === RUN FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 ---- PASS: FuzzDecodeResponse (0.05s) +--- PASS: FuzzDecodeResponse (0.04s) --- PASS: FuzzDecodeResponse/0274b170c6fe2654ca5418a914b804e9c7cc5d8e5c2a7c5fcf5c29540ec5ae52 (0.00s) --- PASS: FuzzDecodeResponse/24d35771ef1fe0645d90b061e13a777faea328736483ec2833b63950d26b5399 (0.00s) --- PASS: FuzzDecodeResponse/2e5d0b26626f2d2dd6fb423e1e1cc432277ae9877c622fe6ca067e247bc11c9d (0.00s) @@ -794,27 +837,27 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 2.666s +ok blitiri.com.ar/go/chasquid/internal/auth 1.975s === RUN TestEmptyStruct ---- PASS: TestEmptyStruct (0.03s) +--- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_3066969452" ---- PASS: TestEmptyConfig (0.02s) + testlib.go:32: test directory: "/tmp/testlib_1165342830" +--- PASS: TestEmptyConfig (0.01s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_3865634735" ---- PASS: TestFullConfig (0.02s) + testlib.go:32: test directory: "/tmp/testlib_2545676956" +--- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_1450108925" + testlib.go:32: test directory: "/tmp/testlib_375368966" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_2832617380" + testlib.go:32: test directory: "/tmp/testlib_2693969524" --- PASS: TestBrokenOverride (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.236s +ok blitiri.com.ar/go/chasquid/internal/config 0.063s === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_172889036" + testlib.go:32: test directory: "/tmp/testlib_571376016" --- PASS: TestMDA (0.02s) === RUN TestMDATimeout _ mda.go:67 Courier.MDA to@local: error: operation timed out @@ -822,17 +865,17 @@ === 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.03s) +--- PASS: TestMDABadCommandLine (0.02s) === RUN TestExitCode mda_test.go:71: util/exitcode not found, running from outside repo? --- SKIP: TestExitCode (0.00s) === RUN TestSanitize --- PASS: TestSanitize (0.00s) === RUN TestSMTP - testlib.go:32: test directory: "/tmp/testlib_2098240125" - testlib.go:32: test directory: "/tmp/testlib_1150729620" -_ smtp.go:125 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::46667: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::46667: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_17887868" + testlib.go:32: test directory: "/tmp/testlib_3231104252" +_ smtp.go:125 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::45761: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::45761: too many colons in address fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -840,26 +883,26 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestSMTP (0.65s) +--- PASS: TestSMTP (0.25s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_3280843973" - testlib.go:32: test directory: "/tmp/testlib_2249513746" + testlib.go:32: test directory: "/tmp/testlib_4262194422" + testlib.go:32: test directory: "/tmp/testlib_3666305343" fakeserver_test.go:97: fakeServer got connection -_ smtp.go:136 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:36444->127.0.0.1:40517: i/o timeout -_ smtp.go:101 Courier.SMTP to@to: error: \"127.0.0.1\" returned transient error: Error saying hello: write tcp 127.0.0.1:36444->127.0.0.1:40517: i/o timeout -_ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:36444->127.0.0.1:40517: i/o timeout) - smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:36444->127.0.0.1:40517: i/o timeout) +_ smtp.go:136 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:48704->127.0.0.1:41707: i/o timeout +_ smtp.go:101 Courier.SMTP to@to: error: \"127.0.0.1\" returned transient error: Error saying hello: write tcp 127.0.0.1:48704->127.0.0.1:41707: i/o timeout +_ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:48704->127.0.0.1:41707: i/o timeout) + smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:48704->127.0.0.1:41707: i/o timeout) fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_3099040424" - testlib.go:32: test directory: "/tmp/testlib_3349071802" + testlib.go:32: test directory: "/tmp/testlib_243393098" + testlib.go:32: test directory: "/tmp/testlib_594873084" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" _ smtp.go:187 Courier.SMTP to@to: error: MAIL+RCPT 501 mail error smtp_test.go:140: failed as expected: MAIL+RCPT 501 mail error fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_2104417996" - testlib.go:32: test directory: "/tmp/testlib_2347038057" + testlib.go:32: test directory: "/tmp/testlib_3464038748" + testlib.go:32: test directory: "/tmp/testlib_3932188227" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -867,8 +910,8 @@ _ smtp.go:187 Courier.SMTP to@to: error: MAIL+RCPT 501 rcpt error smtp_test.go:140: failed as expected: MAIL+RCPT 501 rcpt error fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_3983658688" - testlib.go:32: test directory: "/tmp/testlib_694435453" + testlib.go:32: test directory: "/tmp/testlib_2431623455" + testlib.go:32: test directory: "/tmp/testlib_137411663" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -877,8 +920,8 @@ _ smtp.go:192 Courier.SMTP to@to: error: DATA 554 data error smtp_test.go:140: failed as expected: DATA 554 data error fakeserver_test.go:131: fakeServer exiting: unexpected EOF - testlib.go:32: test directory: "/tmp/testlib_2079793380" - testlib.go:32: test directory: "/tmp/testlib_3350472472" + testlib.go:32: test directory: "/tmp/testlib_349626163" + testlib.go:32: test directory: "/tmp/testlib_1248150500" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -887,9 +930,9 @@ _ smtp.go:201 Courier.SMTP to@to: error: DATA closing 551 data response error smtp_test.go:140: failed as expected: DATA closing 551 data response error fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestSMTPErrors (9.02s) +--- PASS: TestSMTPErrors (3.27s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_1914561447" + testlib.go:32: test directory: "/tmp/testlib_1675013175" _ smtp.go:82 Courier.SMTP to@to: error: Could not find mail server: smtp_test.go:158: got permanent failure, as expected: Could not find mail server: --- PASS: TestNoMXServer (0.00s) @@ -902,10 +945,10 @@ === RUN TestMXLookupError --- PASS: TestMXLookupError (0.00s) === RUN TestLookupInvalidDomain ---- PASS: TestLookupInvalidDomain (0.01s) +--- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_4293555730" - testlib.go:32: test directory: "/tmp/testlib_743714123" + testlib.go:32: test directory: "/tmp/testlib_3285676655" + testlib.go:32: test directory: "/tmp/testlib_4238433432" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -916,7 +959,7 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_2741860590" + testlib.go:32: test directory: "/tmp/testlib_95778357" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" _ domaininfo.go:144 DomainInfo.Outgoing to: error: to outgoing denied: PLAIN < TLS_INSECURE @@ -924,10 +967,10 @@ _ smtp.go:101 Courier.SMTP to@to: error: \"localhost\" returned transient error: Security level check failed (level:PLAIN) _ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: Security level check failed (level:PLAIN)) fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestTLS (3.60s) +--- PASS: TestTLS (0.80s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_1693586241" - testlib.go:32: test directory: "/tmp/testlib_1451473284" + testlib.go:32: test directory: "/tmp/testlib_2657485483" + testlib.go:32: test directory: "/tmp/testlib_4046722405" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -935,10 +978,10 @@ _ smtp.go:101 Courier.SMTP to@to: error: \"localhost\" returned transient error: TLS error: 500 starttls err _ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: TLS error: 500 starttls err) fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestTLSError (2.33s) +--- PASS: TestTLSError (0.72s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_1443718926" - testlib.go:32: test directory: "/tmp/testlib_1638634150" + testlib.go:32: test directory: "/tmp/testlib_3842104418" + testlib.go:32: test directory: "/tmp/testlib_1005518157" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -946,7 +989,7 @@ _ domaininfo.go:152 DomainInfo.Outgoing to: to outgoing level raised: TLS_INSECURE > PLAIN _ smtp.go:179 test test: error: invalid security level (TLS_INSECURE) for STS policy fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_3469337951" + testlib.go:32: test directory: "/tmp/testlib_2121417899" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -957,24 +1000,24 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestSTSPolicyEnforcement (2.45s) +--- PASS: TestSTSPolicyEnforcement (1.59s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 18.417s +ok blitiri.com.ar/go/chasquid/internal/courier 6.882s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_3067479325" + testlib.go:32: test directory: "/tmp/testlib_1930934913" _ domaininfo.go:116 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:108 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:108 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE ---- PASS: TestBasic (0.01s) +--- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_438058910" + testlib.go:32: test directory: "/tmp/testlib_1250735826" _ domaininfo.go:116 DomainInfo.Incoming insecure: insecure incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing insecure: insecure outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:116 DomainInfo.Incoming secure: secure incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing secure: secure outgoing level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.01s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_4067999821" + testlib.go:32: test directory: "/tmp/testlib_3957192201" _ domaininfo.go:116 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing pisis: pisis outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:116 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_SECURE > TLS_INSECURE @@ -987,23 +1030,23 @@ _ domaininfo.go:144 DomainInfo.Outgoing ssip: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:108 DomainInfo.Incoming ssip: error: ssip incoming denied: PLAIN < TLS_SECURE _ domaininfo.go:144 DomainInfo.Outgoing ssip: error: ssip outgoing denied: PLAIN < TLS_SECURE ---- PASS: TestProgressions (0.02s) +--- PASS: TestProgressions (0.01s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_1958857844" + testlib.go:32: test directory: "/tmp/testlib_742979383" _ domaininfo.go:116 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto: syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents --- PASS: TestErrors (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.255s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.162s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_4293503426" ---- PASS: TestAutodetect (0.03s) + testlib.go:32: test directory: "/tmp/testlib_3571956763" +--- PASS: TestAutodetect (0.01s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.123s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.042s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -1011,13 +1054,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.052s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.035s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.153s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.036s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -1042,15 +1085,15 @@ 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.053s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.031s === RUN TestLogger ---- PASS: TestLogger (0.01s) +--- PASS: TestLogger (0.00s) === RUN TestDefault ---- PASS: TestDefault (0.01s) +--- PASS: TestDefault (0.00s) === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.305s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.077s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1058,23 +1101,23 @@ === RUN TestHistogramEmpty --- PASS: TestHistogramEmpty (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.18s) +--- PASS: TestHTTP (0.08s) === RUN TestHTTPLong ---- PASS: TestHTTPLong (0.08s) +--- PASS: TestHTTPLong (0.04s) === RUN TestHTTPErrors ---- PASS: TestHTTPErrors (0.19s) +--- PASS: TestHTTPErrors (0.09s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.17s) +--- PASS: TestHTTPUroboro (0.08s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.17s) +--- PASS: TestHTTPDeep (0.04s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler ---- PASS: TestRegisterHandler (0.06s) +--- PASS: TestRegisterHandler (0.01s) === RUN TestBasic --- PASS: TestBasic (0.00s) === RUN TestLong ---- PASS: TestLong (0.01s) +--- PASS: TestLong (0.00s) === RUN TestIsError --- PASS: TestIsError (0.00s) === RUN TestFindViaRef @@ -1086,7 +1129,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 1.119s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.537s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1101,7 +1144,7 @@ === RUN FuzzUser/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 === RUN FuzzUser/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzUser/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzUser (0.03s) +--- PASS: FuzzUser (0.02s) --- PASS: FuzzUser/263da65bb5a59369f294d26a64a36a989a9a36ed5c60950b123e395bedbe881c (0.00s) --- PASS: FuzzUser/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzUser/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 (0.00s) @@ -1125,7 +1168,7 @@ === RUN FuzzAddr/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzAddr/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 === RUN FuzzAddr/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzAddr (0.01s) +--- PASS: FuzzAddr (0.02s) --- PASS: FuzzAddr/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzAddr/7aba1e0ef80990ccac3731800dbb0267c4c8b7156d4da3b8a5f1b57a570adfb8 (0.00s) --- PASS: FuzzAddr/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 (0.00s) @@ -1137,48 +1180,48 @@ === RUN FuzzDomainToUnicode/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 === RUN FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzDomainToUnicode (0.05s) +--- PASS: FuzzDomainToUnicode (0.02s) --- PASS: FuzzDomainToUnicode/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzDomainToUnicode/7aba1e0ef80990ccac3731800dbb0267c4c8b7156d4da3b8a5f1b57a570adfb8 (0.00s) --- PASS: FuzzDomainToUnicode/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 (0.00s) --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.145s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.105s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_2533621462" ---- PASS: TestBin (0.01s) + testlib.go:32: test directory: "/tmp/testlib_1692760206" +--- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_4247853047" + testlib.go:32: test directory: "/tmp/testlib_2694257760" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_1737817105" ---- PASS: TestStore (0.02s) + testlib.go:32: test directory: "/tmp/testlib_1002044633" +--- PASS: TestStore (0.01s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_2965405756" + testlib.go:32: test directory: "/tmp/testlib_4011668673" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_3208764113" + testlib.go:32: test directory: "/tmp/testlib_2301260341" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.127s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.072s ? 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: Tue, 23 May 2023 11:04:27 -1200 + Message-ID: + Date: Wed, 24 May 2023 13:15:44 +1400 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="8dxyysO7ysE" + boundary="fqk8kcNnd3Q" - --8dxyysO7ysE + --fqk8kcNnd3Q Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1203,7 +1246,7 @@ oh! the humanity! - --8dxyysO7ysE + --fqk8kcNnd3Q Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1234,7 +1277,7 @@ - --8dxyysO7ysE + --fqk8kcNnd3Q Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1244,75 +1287,75 @@ Data ñaca. - --8dxyysO7ysE-- + --fqk8kcNnd3Q-- ---- PASS: TestDSN (0.01s) +--- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_934017359" -_ queue.go:308 Queue.SendLoop xGEuPDyDFBU: from from -_ queue.go:367 Queue.SendLoop xGEuPDyDFBU: nodomain sent -_ queue.go:367 Queue.SendLoop xGEuPDyDFBU: am@loco sent -_ queue.go:367 Queue.SendLoop xGEuPDyDFBU: x@remote sent -_ queue.go:342 Queue.SendLoop xGEuPDyDFBU: all done ---- PASS: TestBasic (0.04s) + testlib.go:32: test directory: "/tmp/testlib_2353589888" +_ queue.go:308 Queue.SendLoop ujVbG4woWqg: from from +_ queue.go:367 Queue.SendLoop ujVbG4woWqg: nodomain sent +_ queue.go:367 Queue.SendLoop ujVbG4woWqg: am@loco sent +_ queue.go:367 Queue.SendLoop ujVbG4woWqg: x@remote sent +_ queue.go:342 Queue.SendLoop ujVbG4woWqg: all done +--- PASS: TestBasic (0.02s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_325330206" -_ queue.go:308 Queue.SendLoop 9jWBEmMLv04: from from@loco -_ queue.go:465 Queue.SendLoop 9jWBEmMLv04: queued DSN: Qkf2zNRNLqU -_ queue.go:342 Queue.SendLoop 9jWBEmMLv04: all done -_ queue.go:308 Queue.SendLoop Qkf2zNRNLqU: from <> -_ queue.go:367 Queue.SendLoop Qkf2zNRNLqU: from@loco sent -_ queue.go:342 Queue.SendLoop Qkf2zNRNLqU: all done ---- PASS: TestDSNOnTimeout (0.02s) + testlib.go:32: test directory: "/tmp/testlib_4187599251" +_ queue.go:308 Queue.SendLoop 4WazmePf7BI: from from@loco +_ queue.go:465 Queue.SendLoop 4WazmePf7BI: queued DSN: Gk4KAU0JQtw +_ queue.go:342 Queue.SendLoop 4WazmePf7BI: all done +_ queue.go:308 Queue.SendLoop Gk4KAU0JQtw: from <> +_ queue.go:367 Queue.SendLoop Gk4KAU0JQtw: from@loco sent +_ queue.go:342 Queue.SendLoop Gk4KAU0JQtw: all done +--- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_3725828379" -_ queue.go:308 Queue.SendLoop -5AbMRyjHVc: from from -_ queue.go:367 Queue.SendLoop -5AbMRyjHVc: ata@hualpa sent -_ queue.go:367 Queue.SendLoop -5AbMRyjHVc: pq@loco sent -_ queue.go:367 Queue.SendLoop -5AbMRyjHVc: rs@loco sent ---- PASS: TestAliases (0.02s) + testlib.go:32: test directory: "/tmp/testlib_1128568799" +_ queue.go:308 Queue.SendLoop UwToTyu2PaI: from from +_ queue.go:367 Queue.SendLoop UwToTyu2PaI: ata@hualpa sent +_ queue.go:367 Queue.SendLoop UwToTyu2PaI: pq@loco sent +_ queue.go:367 Queue.SendLoop UwToTyu2PaI: rs@loco sent +--- PASS: TestAliases (0.01s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_1430375937" -_ queue.go:375 Queue.SendLoop -5AbMRyjHVc: error: failed to write: open /tmp/testlib_3725828379/.m:-5AbMRyjHVc2434277164: no such file or directory -_ queue.go:342 Queue.SendLoop -5AbMRyjHVc: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_3725828379/m:-5AbMRyjHVc": remove /tmp/testlib_3725828379/m:-5AbMRyjHVc: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_1497282883" _ queue.go:164 Queue.Put from: error: queue full ---- PASS: TestFullQueue (0.02s) -_ queue.go:308 Queue.SendLoop uFRs3xJB2RY: from from -_ queue.go:367 Queue.SendLoop uFRs3xJB2RY: to sent -_ queue.go:375 Queue.SendLoop uFRs3xJB2RY: error: failed to write: open /tmp/testlib_1430375937/.m:uFRs3xJB2RY1024974088: no such file or directory -_ queue.go:342 Queue.SendLoop uFRs3xJB2RY: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_1430375937/m:uFRs3xJB2RY": remove /tmp/testlib_1430375937/m:uFRs3xJB2RY: no such file or directory +--- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_2450134567" ---- PASS: TestPipes (0.06s) + testlib.go:32: test directory: "/tmp/testlib_2648224025" +_ queue.go:308 Queue.SendLoop RTjQUgFTQt8: from from +_ queue.go:367 Queue.SendLoop RTjQUgFTQt8: to sent +_ queue.go:375 Queue.SendLoop RTjQUgFTQt8: error: failed to write: open /tmp/testlib_1497282883/.m:RTjQUgFTQt81032160615: no such file or directory +_ queue.go:342 Queue.SendLoop RTjQUgFTQt8: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_1497282883/m:RTjQUgFTQt8": remove /tmp/testlib_1497282883/m:RTjQUgFTQt8: no such file or directory +_ queue.go:375 Queue.SendLoop UwToTyu2PaI: error: failed to write: open /tmp/testlib_1128568799/.m:UwToTyu2PaI4032681198: no such file or directory +_ queue.go:342 Queue.SendLoop UwToTyu2PaI: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_1128568799/m:UwToTyu2PaI": remove /tmp/testlib_1128568799/m:UwToTyu2PaI: no such file or directory +--- PASS: TestPipes (0.02s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_1599727509" -_ queue.go:308 Queue.SendLoop sapltxOrl18: from from@loco -_ queue.go:367 Queue.SendLoop sapltxOrl18: to@to sent -_ queue.go:342 Queue.SendLoop sapltxOrl18: all done ---- PASS: TestSerialization (0.02s) + testlib.go:32: test directory: "/tmp/testlib_2285393170" +_ queue.go:308 Queue.SendLoop k8GEQdArgAs: from from@loco +_ queue.go:367 Queue.SendLoop k8GEQdArgAs: to@to sent +_ queue.go:342 Queue.SendLoop k8GEQdArgAs: all done +--- PASS: TestSerialization (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.597s +ok blitiri.com.ar/go/chasquid/internal/queue 0.216s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_1601433045" ---- PASS: TestWriteFile (0.09s) + testlib.go:32: test directory: "/tmp/testlib_1883320965" +--- PASS: TestWriteFile (0.01s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_3401524350" + testlib.go:32: test directory: "/tmp/testlib_636170817" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_3987939062" ---- PASS: TestWriteFileWithFailingOp (0.01s) + testlib.go:32: test directory: "/tmp/testlib_1830024321" +--- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.205s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.058s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.026s +ok blitiri.com.ar/go/chasquid/internal/set 0.017s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1326,21 +1369,21 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.46s) +--- PASS: TestLineTooLong (0.23s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.540s -_ server.go:218 Server listening on 127.0.0.1:39399 (SMTP) -2023-05-23 11:04:44.312840 daemon listening on 127.0.0.1:39399 -_ server.go:218 Server listening on 127.0.0.1:36213 (submission) -2023-05-23 11:04:44.313751 daemon listening on 127.0.0.1:36213 -_ server.go:218 Server listening on 127.0.0.1:42781 (submission+TLS) -2023-05-23 11:04:44.314049 daemon listening on 127.0.0.1:42781 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.275s +_ server.go:218 Server listening on 127.0.0.1:34327 (SMTP) +2023-05-24 13:15:46.697976 daemon listening on 127.0.0.1:34327 +_ server.go:218 Server listening on 127.0.0.1:40899 (submission) +2023-05-24 13:15:46.698386 daemon listening on 127.0.0.1:40899 +_ server.go:218 Server listening on 127.0.0.1:36883 (submission+TLS) +2023-05-24 13:15:46.698494 daemon listening on 127.0.0.1:36883 === RUN TestSecLevel -_ conn.go:189 SMTP.Conn 127.0.0.1:49024: error: error completing TLS handshake: EOF _ domaininfo.go:116 DomainInfo.Incoming slc: slc incoming level raised: TLS_CLIENT > PLAIN _ domaininfo.go:108 DomainInfo.Incoming slc: error: slc incoming denied: PLAIN < TLS_CLIENT _ conn.go:533 testconn testconn: error: security level check for slc failed (PLAIN) ---- PASS: TestSecLevel (0.02s) +_ conn.go:189 SMTP.Conn 127.0.0.1:42868: error: error completing TLS handshake: EOF +--- PASS: TestSecLevel (0.01s) === RUN TestIsHeader --- PASS: TestIsHeader (0.00s) === RUN TestReadUntilDot @@ -1375,244 +1418,244 @@ === RUN TestSanitizeEHLODomain --- PASS: TestSanitizeEHLODomain (0.00s) === RUN TestSimple -_ conn.go:684 SMTP.Conn 127.0.0.1:42596: Queued from from@from to [to@localhost] - Ri9ZcUxt9o0 -2023-05-23 11:04:44.516938 Ri9ZcUxt9o0 from=from@from queued ip=127.0.0.1:42596 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Ri9ZcUxt9o0: from from@from -_ queue.go:367 Queue.SendLoop Ri9ZcUxt9o0: testuser@localhost sent -2023-05-23 11:04:44.517782 Ri9ZcUxt9o0 from=from@from to=testuser@localhost sent ---- PASS: TestSimple (0.06s) +_ conn.go:684 SMTP.Conn 127.0.0.1:47990: Queued from from@from to [to@localhost] - EAdeCjlTQ6U +2023-05-24 13:15:46.819011 EAdeCjlTQ6U from=from@from queued ip=127.0.0.1:47990 to=[to@localhost] +_ queue.go:308 Queue.SendLoop EAdeCjlTQ6U: from from@from +_ queue.go:367 Queue.SendLoop EAdeCjlTQ6U: testuser@localhost sent +2023-05-24 13:15:46.819405 EAdeCjlTQ6U from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop EAdeCjlTQ6U: all done +2023-05-24 13:15:46.820174 EAdeCjlTQ6U from=from@from all done +--- PASS: TestSimple (0.01s) === RUN TestSimpleTLS -_ queue.go:342 Queue.SendLoop Ri9ZcUxt9o0: all done -2023-05-23 11:04:44.537976 Ri9ZcUxt9o0 from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:42600: Queued from from@from to [to@localhost] - lqbutHGmZQY -2023-05-23 11:04:44.614654 lqbutHGmZQY from=from@from queued ip=127.0.0.1:42600 to=[to@localhost] -_ queue.go:308 Queue.SendLoop lqbutHGmZQY: from from@from -_ queue.go:367 Queue.SendLoop lqbutHGmZQY: testuser@localhost sent -2023-05-23 11:04:44.620026 lqbutHGmZQY from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop lqbutHGmZQY: all done -2023-05-23 11:04:44.622397 lqbutHGmZQY from=from@from all done ---- PASS: TestSimpleTLS (0.10s) +_ conn.go:684 SMTP.Conn 127.0.0.1:47994: Queued from from@from to [to@localhost] - fj-V0KBKJWk +2023-05-24 13:15:46.845019 fj-V0KBKJWk from=from@from queued ip=127.0.0.1:47994 to=[to@localhost] +_ queue.go:308 Queue.SendLoop fj-V0KBKJWk: from from@from +_ queue.go:367 Queue.SendLoop fj-V0KBKJWk: testuser@localhost sent +2023-05-24 13:15:46.845327 fj-V0KBKJWk from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop fj-V0KBKJWk: all done +2023-05-24 13:15:46.845967 fj-V0KBKJWk from=from@from all done +--- PASS: TestSimpleTLS (0.03s) === RUN TestManyEmails -_ conn.go:684 SMTP.Conn 127.0.0.1:42612: Queued from from@from to [to@localhost] - c6Tr62OSDN4 -2023-05-23 11:04:44.784601 c6Tr62OSDN4 from=from@from queued ip=127.0.0.1:42612 to=[to@localhost] -_ queue.go:308 Queue.SendLoop c6Tr62OSDN4: from from@from -_ queue.go:367 Queue.SendLoop c6Tr62OSDN4: testuser@localhost sent -2023-05-23 11:04:44.785655 c6Tr62OSDN4 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop c6Tr62OSDN4: all done -2023-05-23 11:04:44.798372 c6Tr62OSDN4 from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:42612: Queued from from@from to [to@localhost] - Zf4FcxxMDsE -2023-05-23 11:04:44.800594 Zf4FcxxMDsE from=from@from queued ip=127.0.0.1:42612 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Zf4FcxxMDsE: from from@from -_ queue.go:367 Queue.SendLoop Zf4FcxxMDsE: testuser@localhost sent -2023-05-23 11:04:44.801504 Zf4FcxxMDsE from=from@from to=testuser@localhost sent -_ conn.go:684 SMTP.Conn 127.0.0.1:42612: Queued from from@from to [to@localhost] - -FcPjDJ8IfE -2023-05-23 11:04:44.813827 -FcPjDJ8IfE from=from@from queued ip=127.0.0.1:42612 to=[to@localhost] -_ queue.go:308 Queue.SendLoop -FcPjDJ8IfE: from from@from -_ queue.go:367 Queue.SendLoop -FcPjDJ8IfE: testuser@localhost sent -2023-05-23 11:04:44.814734 -FcPjDJ8IfE from=from@from to=testuser@localhost sent ---- PASS: TestManyEmails (0.19s) +_ conn.go:684 SMTP.Conn 127.0.0.1:48004: Queued from from@from to [to@localhost] - M9Zn2MbGFzY +2023-05-24 13:15:46.876557 M9Zn2MbGFzY from=from@from queued ip=127.0.0.1:48004 to=[to@localhost] +_ queue.go:308 Queue.SendLoop M9Zn2MbGFzY: from from@from +_ queue.go:367 Queue.SendLoop M9Zn2MbGFzY: testuser@localhost sent +2023-05-24 13:15:46.876810 M9Zn2MbGFzY from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop M9Zn2MbGFzY: all done +2023-05-24 13:15:46.877414 M9Zn2MbGFzY from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:48004: Queued from from@from to [to@localhost] - eK5-vyNEMyA +2023-05-24 13:15:46.886005 eK5-vyNEMyA from=from@from queued ip=127.0.0.1:48004 to=[to@localhost] +_ queue.go:308 Queue.SendLoop eK5-vyNEMyA: from from@from +_ queue.go:367 Queue.SendLoop eK5-vyNEMyA: testuser@localhost sent +2023-05-24 13:15:46.886251 eK5-vyNEMyA from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop eK5-vyNEMyA: all done +2023-05-24 13:15:46.886866 eK5-vyNEMyA from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:48004: Queued from from@from to [to@localhost] - dHVPIR11AaM +2023-05-24 13:15:46.888567 dHVPIR11AaM from=from@from queued ip=127.0.0.1:48004 to=[to@localhost] +_ queue.go:308 Queue.SendLoop dHVPIR11AaM: from from@from +_ queue.go:367 Queue.SendLoop dHVPIR11AaM: testuser@localhost sent +2023-05-24 13:15:46.888819 dHVPIR11AaM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop dHVPIR11AaM: all done +2023-05-24 13:15:46.889445 dHVPIR11AaM from=from@from all done +--- PASS: TestManyEmails (0.04s) === RUN TestAuth -_ queue.go:342 Queue.SendLoop Zf4FcxxMDsE: all done -2023-05-23 11:04:44.828031 Zf4FcxxMDsE from=from@from all done -_ queue.go:342 Queue.SendLoop -FcPjDJ8IfE: all done -2023-05-23 11:04:44.833470 -FcPjDJ8IfE from=from@from all done -2023-05-23 11:04:46.005921 127.0.0.1:59662 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:59662: Queued from testuser@localhost to [to@localhost] - 2NoAZcvaLE8 -2023-05-23 11:04:46.025720 2NoAZcvaLE8 from=testuser@localhost queued ip=127.0.0.1:59662 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 2NoAZcvaLE8: from testuser@localhost -_ queue.go:367 Queue.SendLoop 2NoAZcvaLE8: testuser@localhost sent -2023-05-23 11:04:46.026630 2NoAZcvaLE8 from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuth (1.21s) +2023-05-24 13:15:47.221083 127.0.0.1:49474 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:49474: Queued from testuser@localhost to [to@localhost] - TR4rmxTqVvI +2023-05-24 13:15:47.226757 TR4rmxTqVvI from=testuser@localhost queued ip=127.0.0.1:49474 to=[to@localhost] +_ queue.go:308 Queue.SendLoop TR4rmxTqVvI: from testuser@localhost +_ queue.go:367 Queue.SendLoop TR4rmxTqVvI: testuser@localhost sent +2023-05-24 13:15:47.227159 TR4rmxTqVvI from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop TR4rmxTqVvI: all done +2023-05-24 13:15:47.228002 TR4rmxTqVvI from=testuser@localhost all done +--- PASS: TestAuth (0.34s) === RUN TestSubmissionWithoutAuth -_ queue.go:342 Queue.SendLoop 2NoAZcvaLE8: all done -2023-05-23 11:04:46.034202 2NoAZcvaLE8 from=testuser@localhost all done -_ conn.go:298 SMTP.Conn 127.0.0.1:59676: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated ---- PASS: TestSubmissionWithoutAuth (0.14s) +_ conn.go:298 SMTP.Conn 127.0.0.1:49484: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +--- PASS: TestSubmissionWithoutAuth (0.01s) === RUN TestAuthOnTLS -2023-05-23 11:04:47.119366 127.0.0.1:49030 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:49030: Queued from testuser@localhost to [to@localhost] - N8GylZ_4RAM -2023-05-23 11:04:47.139395 N8GylZ_4RAM from=testuser@localhost queued ip=127.0.0.1:49030 to=[to@localhost] -_ queue.go:308 Queue.SendLoop N8GylZ_4RAM: from testuser@localhost -_ queue.go:367 Queue.SendLoop N8GylZ_4RAM: testuser@localhost sent -2023-05-23 11:04:47.140301 N8GylZ_4RAM from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop N8GylZ_4RAM: all done -2023-05-23 11:04:47.142225 N8GylZ_4RAM from=testuser@localhost all done ---- PASS: TestAuthOnTLS (0.98s) +2023-05-24 13:15:47.455450 127.0.0.1:42876 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:42876: Queued from testuser@localhost to [to@localhost] - mHOzwwmTzHs +2023-05-24 13:15:47.462106 mHOzwwmTzHs from=testuser@localhost queued ip=127.0.0.1:42876 to=[to@localhost] +_ queue.go:308 Queue.SendLoop mHOzwwmTzHs: from testuser@localhost +_ queue.go:367 Queue.SendLoop mHOzwwmTzHs: testuser@localhost sent +2023-05-24 13:15:47.462365 mHOzwwmTzHs from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop mHOzwwmTzHs: all done +2023-05-24 13:15:47.462916 mHOzwwmTzHs from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.22s) === RUN TestAuthOnSMTP -2023-05-23 11:04:47.972935 127.0.0.1:42616 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:42616: Queued from testuser@localhost to [to@localhost] - bDIqR_ITyOc -2023-05-23 11:04:47.985882 bDIqR_ITyOc from=testuser@localhost queued ip=127.0.0.1:42616 to=[to@localhost] -_ queue.go:308 Queue.SendLoop bDIqR_ITyOc: from testuser@localhost -_ queue.go:367 Queue.SendLoop bDIqR_ITyOc: testuser@localhost sent -2023-05-23 11:04:47.986949 bDIqR_ITyOc from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnSMTP (0.84s) +2023-05-24 13:15:47.616848 127.0.0.1:34420 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:34420: Queued from testuser@localhost to [to@localhost] - n9BlhFfKR3I +2023-05-24 13:15:47.618793 n9BlhFfKR3I from=testuser@localhost queued ip=127.0.0.1:34420 to=[to@localhost] +_ queue.go:308 Queue.SendLoop n9BlhFfKR3I: from testuser@localhost +_ queue.go:367 Queue.SendLoop n9BlhFfKR3I: testuser@localhost sent +2023-05-24 13:15:47.619050 n9BlhFfKR3I from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnSMTP (0.16s) === RUN TestBrokenAuth -_ queue.go:342 Queue.SendLoop bDIqR_ITyOc: all done -2023-05-23 11:04:47.993655 bDIqR_ITyOc from=testuser@localhost all done -_ conn.go:1075 SMTP.Conn 127.0.0.1:59690: error: error authenticating \"user\"@\"broken\": failed to auth -2023-05-23 11:04:48.184356 127.0.0.1:59690 auth failed for user@broken -_ conn.go:298 SMTP.Conn 127.0.0.1:59690: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:298 SMTP.Conn 127.0.0.1:59690: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.20s) +_ queue.go:342 Queue.SendLoop n9BlhFfKR3I: all done +2023-05-24 13:15:47.630156 n9BlhFfKR3I from=testuser@localhost all done +_ conn.go:1075 SMTP.Conn 127.0.0.1:59556: error: error authenticating \"user\"@\"broken\": failed to auth +2023-05-24 13:15:47.736464 127.0.0.1:59556 auth failed for user@broken +_ conn.go:298 SMTP.Conn 127.0.0.1:59556: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:298 SMTP.Conn 127.0.0.1:59556: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:298 SMTP.Conn 127.0.0.1:42624: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42624: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42624: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42624: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:42630: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42630: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42630: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42630: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:42642: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42642: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42642: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42642: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:42650: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42650: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42650: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42650: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:42666: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42666: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42666: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42666: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:42680: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:42680: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:42680: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:42680: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.05s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34428: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34428: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34428: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34428: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34436: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34436: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34436: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34436: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34448: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34448: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34448: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34448: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34452: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34452: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34452: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34452: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34464: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34464: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34464: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34464: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34480: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:34480: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:34480: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34480: error: too many errors, breaking connection +--- PASS: TestWrongMailParsing (0.02s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:298 SMTP.Conn 127.0.0.1:42698: error: RCPT failed: 503 5.5.1 Sender not yet given ---- PASS: TestRcptBeforeMail (0.01s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34498: error: RCPT failed: 503 5.5.1 Sender not yet given +--- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption ---- PASS: TestRcptOption (0.05s) +--- PASS: TestRcptOption (0.03s) === RUN TestRelayForbidden -2023-05-23 11:04:48.309006 127.0.0.1:42718 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:42718: error: RCPT failed: 503 5.7.1 Relay not allowed ---- PASS: TestRelayForbidden (0.01s) +2023-05-24 13:15:47.788877 127.0.0.1:34510 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34510: error: RCPT failed: 503 5.7.1 Relay not allowed +--- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2023-05-23 11:04:49.096732 127.0.0.1:59702 auth succeeded for testuser@localhost -_ conn.go:298 SMTP.Conn 127.0.0.1:59702: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.94s) +2023-05-24 13:15:47.920101 127.0.0.1:59572 auth succeeded for testuser@localhost +_ conn.go:298 SMTP.Conn 127.0.0.1:59572: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.15s) === RUN TestRcptBrokenExists -_ conn.go:596 SMTP.Conn 127.0.0.1:42728: error: error checking if user \"to@broken\" exists: failed to check if user exists -2023-05-23 11:04:49.311955 127.0.0.1:42728 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists -_ conn.go:298 SMTP.Conn 127.0.0.1:42728: error: RCPT failed: 451 4.4.3 Temporary error checking address ---- PASS: TestRcptBrokenExists (0.07s) +_ conn.go:596 SMTP.Conn 127.0.0.1:34520: error: error checking if user \"to@broken\" exists: failed to check if user exists +2023-05-24 13:15:47.949930 127.0.0.1:34520 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists +_ conn.go:298 SMTP.Conn 127.0.0.1:34520: error: RCPT failed: 451 4.4.3 Temporary error checking address +--- PASS: TestRcptBrokenExists (0.01s) === RUN TestRcptUserDoesNotExist -2023-05-23 11:04:49.438360 127.0.0.1:42738 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:298 SMTP.Conn 127.0.0.1:42738: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) ---- PASS: TestRcptUserDoesNotExist (0.13s) +2023-05-24 13:15:47.964535 127.0.0.1:34534 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:298 SMTP.Conn 127.0.0.1:34534: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +--- PASS: TestRcptUserDoesNotExist (0.01s) === RUN TestTooMuchData -_ conn.go:684 SMTP.Conn 127.0.0.1:42752: Queued from from@from to [to@localhost] - zxk0NuXklDs -2023-05-23 11:04:50.984149 zxk0NuXklDs from=from@from queued ip=127.0.0.1:42752 to=[to@localhost] -_ queue.go:308 Queue.SendLoop zxk0NuXklDs: from from@from -_ queue.go:367 Queue.SendLoop zxk0NuXklDs: testuser@localhost sent -2023-05-23 11:04:50.985114 zxk0NuXklDs from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop zxk0NuXklDs: all done -2023-05-23 11:04:51.462891 zxk0NuXklDs from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:42752: Queued from from@from to [to@localhost] - olnT5zTfLgo -2023-05-23 11:04:52.997639 olnT5zTfLgo from=from@from queued ip=127.0.0.1:42752 to=[to@localhost] -_ queue.go:308 Queue.SendLoop olnT5zTfLgo: from from@from -_ queue.go:367 Queue.SendLoop olnT5zTfLgo: testuser@localhost sent -2023-05-23 11:04:52.998492 olnT5zTfLgo from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop olnT5zTfLgo: all done -2023-05-23 11:04:53.510243 olnT5zTfLgo from=from@from all done -_ conn.go:298 SMTP.Conn 127.0.0.1:42752: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:684 SMTP.Conn 127.0.0.1:42752: Queued from from@from to [to@localhost] - 1mFI_7OYn14 -2023-05-23 11:04:56.733375 1mFI_7OYn14 from=from@from queued ip=127.0.0.1:42752 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 1mFI_7OYn14: from from@from -_ queue.go:367 Queue.SendLoop 1mFI_7OYn14: testuser@localhost sent -2023-05-23 11:04:56.734339 1mFI_7OYn14 from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (7.29s) +_ conn.go:684 SMTP.Conn 127.0.0.1:34540: Queued from from@from to [to@localhost] - VSB5uEGIweM +2023-05-24 13:15:48.487455 VSB5uEGIweM from=from@from queued ip=127.0.0.1:34540 to=[to@localhost] +_ queue.go:308 Queue.SendLoop VSB5uEGIweM: from from@from +_ queue.go:367 Queue.SendLoop VSB5uEGIweM: testuser@localhost sent +2023-05-24 13:15:48.487809 VSB5uEGIweM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop VSB5uEGIweM: all done +2023-05-24 13:15:48.549437 VSB5uEGIweM from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:34540: Queued from from@from to [to@localhost] - J1QNBaRjBbw +2023-05-24 13:15:49.022725 J1QNBaRjBbw from=from@from queued ip=127.0.0.1:34540 to=[to@localhost] +_ queue.go:308 Queue.SendLoop J1QNBaRjBbw: from from@from +_ queue.go:367 Queue.SendLoop J1QNBaRjBbw: testuser@localhost sent +2023-05-24 13:15:49.023074 J1QNBaRjBbw from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop J1QNBaRjBbw: all done +2023-05-24 13:15:49.117580 J1QNBaRjBbw from=from@from all done +_ conn.go:298 SMTP.Conn 127.0.0.1:34540: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:684 SMTP.Conn 127.0.0.1:34540: Queued from from@from to [to@localhost] - aXLlvPeTNNA +2023-05-24 13:15:50.255389 aXLlvPeTNNA from=from@from queued ip=127.0.0.1:34540 to=[to@localhost] +_ queue.go:308 Queue.SendLoop aXLlvPeTNNA: from from@from +_ queue.go:367 Queue.SendLoop aXLlvPeTNNA: testuser@localhost sent +2023-05-24 13:15:50.255758 aXLlvPeTNNA from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (2.29s) === RUN TestSimpleCommands -_ conn.go:298 SMTP.Conn 127.0.0.1:39246: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. -_ conn.go:298 SMTP.Conn 127.0.0.1:39246: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. ---- PASS: TestSimpleCommands (0.01s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34556: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. +_ conn.go:298 SMTP.Conn 127.0.0.1:34556: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +--- PASS: TestSimpleCommands (0.00s) === RUN TestLongLines -_ conn.go:298 SMTP.Conn 127.0.0.1:39260: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:322 SMTP.Conn 127.0.0.1:39260: error: exiting with error: line too long ---- PASS: TestLongLines (0.02s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34570: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:322 SMTP.Conn 127.0.0.1:34570: error: exiting with error: line too long +--- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.01s) === RUN TestRepeatedStartTLS -_ conn.go:298 SMTP.Conn 127.0.0.1:39278: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestRepeatedStartTLS (0.06s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34598: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestRepeatedStartTLS (0.02s) === RUN TestStartTLSOnTLS -_ conn.go:298 SMTP.Conn 127.0.0.1:58274: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestStartTLSOnTLS (0.05s) +_ conn.go:298 SMTP.Conn 127.0.0.1:56714: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestStartTLSOnTLS (0.04s) === RUN FuzzConnection +_ queue.go:342 Queue.SendLoop aXLlvPeTNNA: all done +2023-05-24 13:15:50.374147 aXLlvPeTNNA from=from@from all done === RUN FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 -_ conn.go:298 SMTP.Conn 127.0.0.1:39286: error: RCPT failed: 500 5.5.2 Unknown command -_ conn.go:298 SMTP.Conn 127.0.0.1:39286: error: RCPT failed: 500 5.5.4 Malformed command: EOF -_ conn.go:298 SMTP.Conn 127.0.0.1:39286: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:39286: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34606: error: RCPT failed: 500 5.5.2 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:34606: error: RCPT failed: 500 5.5.4 Malformed command: EOF +_ conn.go:298 SMTP.Conn 127.0.0.1:34606: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:34606: error: too many errors, breaking connection === RUN FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 -_ conn.go:298 SMTP.Conn 127.0.0.1:39298: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! -_ conn.go:298 SMTP.Conn 127.0.0.1:39298: error: DATA failed: 503 5.5.1 Sender not yet given -2023-05-23 11:04:56.966707 127.0.0.1:39298 rejected from=a@b to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:39298: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:305 SMTP.Conn 127.0.0.1:39298: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34622: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:34622: error: DATA failed: 503 5.5.1 Sender not yet given +2023-05-24 13:15:50.403197 127.0.0.1:34622 rejected from=a@b to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34622: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:305 SMTP.Conn 127.0.0.1:34622: error: too many errors, breaking connection === RUN FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f -2023-05-23 11:04:56.972714 127.0.0.1:39306 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:39306: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:39306: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:298 SMTP.Conn 127.0.0.1:39306: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:305 SMTP.Conn 127.0.0.1:39306: error: too many errors, breaking connection +2023-05-24 13:15:50.404443 127.0.0.1:34638 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34638: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34638: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:298 SMTP.Conn 127.0.0.1:34638: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:305 SMTP.Conn 127.0.0.1:34638: error: too many errors, breaking connection === RUN FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe -_ conn.go:298 SMTP.Conn 127.0.0.1:39322: error: MAIL failed: 500 5.5.2 Unknown command -_ conn.go:298 SMTP.Conn 127.0.0.1:39322: error: MAIL failed: 500 5.5.4 Malformed command: EOF -_ conn.go:298 SMTP.Conn 127.0.0.1:39322: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:305 SMTP.Conn 127.0.0.1:39322: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34646: error: MAIL failed: 500 5.5.2 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:34646: error: MAIL failed: 500 5.5.4 Malformed command: EOF +_ conn.go:298 SMTP.Conn 127.0.0.1:34646: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:305 SMTP.Conn 127.0.0.1:34646: error: too many errors, breaking connection === RUN FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 -_ conn.go:298 SMTP.Conn 127.0.0.1:39336: error: HELO failed: 501 Invisible customers are not welcome! -_ conn.go:298 SMTP.Conn 127.0.0.1:39336: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:34656: error: HELO failed: 501 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:34656: error: EHLO failed: 501 Invisible customers are not welcome! === RUN FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf === RUN FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 -2023-05-23 11:04:56.993325 127.0.0.1:39342 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:39342: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:39342: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:298 SMTP.Conn 127.0.0.1:39342: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:305 SMTP.Conn 127.0.0.1:39342: error: too many errors, breaking connection +2023-05-24 13:15:50.414906 127.0.0.1:34664 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34664: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:34664: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:298 SMTP.Conn 127.0.0.1:34664: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:305 SMTP.Conn 127.0.0.1:34664: error: too many errors, breaking connection === RUN FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 -_ conn.go:298 SMTP.Conn 127.0.0.1:39354: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:298 SMTP.Conn 127.0.0.1:39354: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:298 SMTP.Conn 127.0.0.1:39354: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:305 SMTP.Conn 127.0.0.1:39354: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:34680: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:34680: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:34680: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:305 SMTP.Conn 127.0.0.1:34680: error: too many errors, breaking connection === RUN FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded === RUN FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 -_ conn.go:298 SMTP.Conn 127.0.0.1:39370: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:34692: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command === RUN FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c -_ conn.go:298 SMTP.Conn 127.0.0.1:39380: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:298 SMTP.Conn 127.0.0.1:58290: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage -_ conn.go:298 SMTP.Conn 127.0.0.1:58290: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 -2023-05-23 11:04:57.161699 127.0.0.1:58290 auth failed for user@testserver -_ conn.go:298 SMTP.Conn 127.0.0.1:58290: error: AUTH failed: 535 5.7.8 Incorrect user or password -_ conn.go:305 SMTP.Conn 127.0.0.1:58290: error: too many errors, breaking connection ---- PASS: FuzzConnection (0.29s) - --- PASS: FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 (0.01s) - --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.01s) - --- PASS: FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f (0.01s) - --- PASS: FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe (0.01s) - --- PASS: FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 (0.00s) +_ conn.go:298 SMTP.Conn 127.0.0.1:34694: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:56722: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage +_ conn.go:298 SMTP.Conn 127.0.0.1:56722: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 +2023-05-24 13:15:50.564327 127.0.0.1:56722 auth failed for user@testserver +_ conn.go:298 SMTP.Conn 127.0.0.1:56722: error: AUTH failed: 535 5.7.8 Incorrect user or password +_ conn.go:305 SMTP.Conn 127.0.0.1:56722: error: too many errors, breaking connection +--- PASS: FuzzConnection (0.24s) + --- PASS: FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 (0.00s) + --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.00s) + --- PASS: FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f (0.00s) + --- PASS: FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe (0.00s) + --- PASS: FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 (0.01s) --- PASS: FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf (0.00s) - --- PASS: FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 (0.01s) - --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.01s) + --- PASS: FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 (0.00s) + --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.00s) --- PASS: FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded (0.00s) --- PASS: FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 (0.00s) --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.15s) PASS -_ queue.go:342 Queue.SendLoop 1mFI_7OYn14: all done -2023-05-23 11:04:57.217203 1mFI_7OYn14 from=from@from all done -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 14.900s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 4.661s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) === RUN TestCheckPolicy --- PASS: TestCheckPolicy (0.00s) === RUN TestMatchDomain ---- PASS: TestMatchDomain (0.01s) +--- PASS: TestMatchDomain (0.00s) === RUN TestMXIsAllowed --- PASS: TestMXIsAllowed (0.00s) === RUN TestFetch @@ -1623,31 +1666,31 @@ --- PASS: TestFetch (0.01s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.31s) +--- PASS: TestPolicyTooBig (0.10s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_1508450299" + testlib.go:32: test directory: "/tmp/testlib_3119272610" 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.04s) +--- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_1197850853" + testlib.go:32: test directory: "/tmp/testlib_3236146865" 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} 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} ---- PASS: TestCacheBadData (0.03s) +--- PASS: TestCacheBadData (0.01s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_1653450000" + testlib.go:32: test directory: "/tmp/testlib_956637609" 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.03s) +--- PASS: TestCacheRefresh (0.01s) === RUN TestCacheSlashSafe - testlib.go:32: test directory: "/tmp/testlib_2622120699" + testlib.go:32: test directory: "/tmp/testlib_3137429389" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1655,11 +1698,11 @@ === RUN TestHasSTSRecord --- PASS: TestHasSTSRecord (0.00s) === RUN TestHTTPGet ---- PASS: TestHTTPGet (0.02s) +--- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.756s +ok blitiri.com.ar/go/chasquid/internal/sts 0.250s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_719259920" + testlib.go:32: test directory: "/tmp/testlib_1863454802" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -1670,52 +1713,52 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_1363797469" + testlib.go:32: test directory: "/tmp/testlib_2801609352" --- PASS: TestRewrite (0.00s) === RUN TestGetFreePort --- PASS: TestGetFreePort (0.00s) === RUN TestWaitFor --- PASS: TestWaitFor (0.02s) === RUN TestGenerateCert - testlib.go:32: test directory: "/tmp/testlib_4175563785" ---- PASS: TestGenerateCert (0.86s) + testlib.go:32: test directory: "/tmp/testlib_3956272783" +--- PASS: TestGenerateCert (0.68s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (1.13s) +--- PASS: TestGenerateCertBadDir (0.81s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 2.074s +ok blitiri.com.ar/go/chasquid/internal/testlib 1.554s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.096s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.041s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test3561840795" - userdb_test.go:37: file: "/tmp/userdb_test3225481144" ---- PASS: TestEmptyLoad (0.01s) + userdb_test.go:37: file: "/tmp/userdb_test1233507920" + userdb_test.go:37: file: "/tmp/userdb_test1384581551" +--- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test1867594788" ---- PASS: TestWrite (6.62s) + userdb_test.go:37: file: "/tmp/userdb_test3265044852" +--- PASS: TestWrite (2.47s) === RUN TestNew ---- PASS: TestNew (0.58s) +--- PASS: TestNew (0.14s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test3847587749" + userdb_test.go:37: file: "/tmp/userdb_test1635071667" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test2451759550" + userdb_test.go:37: file: "/tmp/userdb_test3797547915" --- PASS: TestPlainScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test2172389354" ---- PASS: TestReload (0.02s) + userdb_test.go:37: file: "/tmp/userdb_test68798591" +--- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test2298469726" ---- PASS: TestRemoveUser (0.60s) + userdb_test.go:37: file: "/tmp/userdb_test3015224019" +--- PASS: TestRemoveUser (0.21s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test398526516" ---- PASS: TestExists (0.60s) + userdb_test.go:37: file: "/tmp/userdb_test2366679787" +--- PASS: TestExists (0.40s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 8.510s +ok blitiri.com.ar/go/chasquid/internal/userdb 3.286s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1778,12 +1821,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/9284/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/9284/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/14427 and its subdirectories -I: Current time: Tue May 23 11:07:46 -12 2023 -I: pbuilder-time-stamp: 1684883266 +I: removing directory /srv/workspace/pbuilder/9284 and its subdirectories +I: Current time: Wed May 24 13:16:52 +14 2023 +I: pbuilder-time-stamp: 1684883812