Diff of the two buildlogs: -- --- b1/build.log 2024-12-02 12:04:21.004608941 +0000 +++ b2/build.log 2024-12-02 12:06:11.275606194 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Dec 2 00:03:05 -12 2024 -I: pbuilder-time-stamp: 1733140985 +I: Current time: Mon Jan 5 08:27:22 +14 2026 +I: pbuilder-time-stamp: 1767551242 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -28,53 +28,85 @@ 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/6681/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/29472/tmp/hooks/D01_modify_environment starting +debug: Running on infom08-i386. +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 Jan 4 18:27 /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/29472/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/29472/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=6 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='i386' + 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]="32" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.2.32(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=5 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='87ce3eecca6944b98af3fa63c8a09340' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - 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='6681' - PS1='# ' - PS2='> ' + INVOCATION_ID=532bc2d360a142c5a701a404f855523c + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=29472 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.pSIpFziR/pbuilderrc_nP62 --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.pSIpFziR/b1 --logfile b1/build.log chasquid_1.14.0-2.dsc' - SUDO_GID='111' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' + 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.pSIpFziR/pbuilderrc_p0eY --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.pSIpFziR/b2 --logfile b2/build.log chasquid_1.14.0-2.dsc' + SUDO_GID=111 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' I: uname -a - Linux infom07-i386 6.1.0-28-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.119-1 (2024-11-22) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.11.5+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.11.5-1~bpo12+1 (2024-11-11) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Aug 4 21:30 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/6681/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Nov 22 2024 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/29472/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -177,7 +209,7 @@ Get: 43 http://deb.debian.org/debian trixie/main i386 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 44 http://deb.debian.org/debian trixie/main i386 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 45 http://deb.debian.org/debian trixie/main i386 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 72.2 MB in 1s (101 MB/s) +Fetched 72.2 MB in 1s (118 MB/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 ... 19950 files and directories currently installed.) @@ -372,7 +404,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/chasquid-1.14.0/ && 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.14.0-2_source.changes +I: user script /srv/workspace/pbuilder/29472/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/29472/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/chasquid-1.14.0/ && 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.14.0-2_source.changes dpkg-buildpackage: info: source package chasquid dpkg-buildpackage: info: source version 1.14.0-2 dpkg-buildpackage: info: source distribution unstable @@ -392,37 +428,37 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/reproducible-path/chasquid-1.14.0' dh_auto_build -- -ldflags " -X main.version=1.14.0-2 -X main.sourceDateTs=1717328439" - cd obj-i686-linux-gnu && go install -trimpath -v -p 6 -ldflags " -X main.version=1.14.0-2 -X main.sourceDateTs=1717328439" 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/dkim 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/localrpc 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-i686-linux-gnu && go install -trimpath -v -p 5 -ldflags " -X main.version=1.14.0-2 -X main.sourceDateTs=1717328439" 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/dkim 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/localrpc 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 +encoding internal/byteorder +internal/cpu internal/unsafeheader -encoding -internal/coverage/rtcov internal/goarch -internal/cpu +internal/coverage/rtcov internal/godebugs +internal/abi +internal/chacha8rand internal/goexperiment internal/goos -internal/abi internal/profilerecord internal/runtime/atomic internal/runtime/syscall -internal/chacha8rand +internal/bytealg runtime/internal/math runtime/internal/sys -internal/bytealg cmp internal/itoa -internal/race internal/runtime/exithook +internal/race math/bits unicode/utf8 +internal/stringslite sync/atomic unicode internal/asan -math internal/msan +math log/internal -internal/stringslite internal/nettrace google.golang.org/protobuf/internal/flags unicode/utf16 @@ -431,135 +467,132 @@ container/list crypto/internal/alias crypto/internal/boring/sig +runtime vendor/golang.org/x/crypto/cryptobyte/asn1 vendor/golang.org/x/crypto/internal/alias blitiri.com.ar/go/chasquid/internal/set -runtime iter +sync internal/weak -internal/reflectlite crypto/subtle -sync -maps +internal/reflectlite slices +maps +internal/bisect internal/testlog internal/singleflight -google.golang.org/protobuf/internal/pragma -internal/bisect runtime/cgo errors -internal/godebug sort -strconv -internal/oserror +google.golang.org/protobuf/internal/pragma io -reflect +internal/oserror +strconv path -vendor/golang.org/x/net/dns/dnsmessage +internal/godebug syscall -math/rand/v2 strings +vendor/golang.org/x/net/dns/dnsmessage +reflect +math/rand/v2 internal/concurrent unique hash hash/fnv bytes net/netip +time +internal/syscall/unix +internal/syscall/execenv +bufio hash/crc32 html -bufio regexp/syntax math/rand -time -internal/syscall/execenv -internal/syscall/unix -regexp crypto crypto/cipher -crypto/internal/randutil -crypto/internal/edwards25519/field -crypto/internal/boring -crypto/aes -crypto/des -crypto/internal/nistec/fiat -crypto/sha512 internal/fmtsort encoding/binary -crypto/internal/edwards25519 -crypto/hmac +crypto/internal/boring io/fs internal/poll -internal/filepathlite context +crypto/internal/randutil +internal/filepathlite embed +crypto/aes encoding/base64 +regexp +crypto/des google.golang.org/protobuf/internal/editiondefaults -vendor/golang.org/x/crypto/chacha20 +crypto/internal/edwards25519/field +crypto/sha512 +crypto/internal/nistec/fiat os +crypto/hmac +crypto/internal/edwards25519 +vendor/golang.org/x/crypto/chacha20 vendor/golang.org/x/crypto/internal/poly1305 vendor/golang.org/x/crypto/hkdf +vendor/golang.org/x/crypto/chacha20poly1305 crypto/md5 crypto/rc4 -vendor/golang.org/x/crypto/chacha20poly1305 -crypto/sha256 crypto/sha1 +crypto/sha256 encoding/pem vendor/golang.org/x/text/transform net/http/internal/ascii golang.org/x/text/transform golang.org/x/text/internal/tag golang.org/x/crypto/pbkdf2 -golang.org/x/crypto/scrypt -golang.org/x/sys/unix golang.org/x/text/runes golang.org/x/text/width -path/filepath -io/ioutil -google.golang.org/protobuf/internal/detrand +golang.org/x/sys/unix fmt net +path/filepath +google.golang.org/protobuf/internal/detrand +io/ioutil blitiri.com.ar/go/chasquid/internal/safeio crypto/internal/nistec -vendor/golang.org/x/sys/cpu -os/exec -vendor/golang.org/x/crypto/sha3 -os/signal -internal/sysinfo -crypto/ecdh -flag log +flag google.golang.org/protobuf/internal/errors google.golang.org/protobuf/encoding/protowire go/token -compress/flate google.golang.org/protobuf/reflect/protoreflect +compress/flate encoding/json -google.golang.org/protobuf/internal/version -net/url -compress/gzip -text/template/parse -math/big google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs +google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order -google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/proto -text/template google.golang.org/protobuf/internal/encoding/defval -encoding/hex -vendor/golang.org/x/text/unicode/bidi -vendor/golang.org/x/text/unicode/norm +compress/gzip +google.golang.org/protobuf/internal/version +net/url +text/template/parse +math/big +crypto/ecdh google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc +text/template +blitiri.com.ar/go/chasquid/internal/protoio +vendor/golang.org/x/sys/cpu +vendor/golang.org/x/crypto/sha3 html/template +encoding/hex +vendor/golang.org/x/text/unicode/bidi crypto/rand -blitiri.com.ar/go/chasquid/internal/protoio crypto/elliptic +google.golang.org/protobuf/internal/encoding/tag +google.golang.org/protobuf/internal/impl crypto/internal/bigmod crypto/internal/boring/bbig encoding/asn1 @@ -568,83 +601,86 @@ crypto/internal/mlkem768 crypto/rsa crypto/dsa +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix vendor/golang.org/x/text/secure/bidirule +vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack +crypto/ecdsa mime -vendor/golang.org/x/net/idna -google.golang.org/protobuf/internal/encoding/tag -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix -google.golang.org/protobuf/internal/impl mime/quotedprintable net/http/internal -crypto/ecdsa blitiri.com.ar/go/chasquid/internal/envelope golang.org/x/text/unicode/bidi +golang.org/x/text/secure/bidirule golang.org/x/text/unicode/norm +vendor/golang.org/x/net/idna golang.org/x/text/internal/language -blitiri.com.ar/go/chasquid/internal/tlsconst -internal/profile -golang.org/x/text/secure/bidirule -text/tabwriter -runtime/pprof -golang.org/x/text/internal/language/compact -golang.org/x/text/language -golang.org/x/net/idna -runtime/trace -runtime/debug -golang.org/x/text/internal -golang.org/x/term -testing -golang.org/x/text/cases -golang.org/x/text/secure/precis log/syslog +blitiri.com.ar/go/log crypto/x509 net/textproto vendor/golang.org/x/net/http/httpproxy -blitiri.com.ar/go/chasquid/internal/normalize -blitiri.com.ar/go/log -blitiri.com.ar/go/chasquid/internal/haproxy -blitiri.com.ar/go/spf +golang.org/x/net/idna vendor/golang.org/x/net/http/httpguts mime/multipart +golang.org/x/text/internal/language/compact +golang.org/x/text/language +os/exec blitiri.com.ar/go/chasquid/internal/dovecot -google.golang.org/protobuf/internal/filetype +blitiri.com.ar/go/chasquid/internal/haproxy net/mail +golang.org/x/text/internal +blitiri.com.ar/go/chasquid/internal/tlsconst +golang.org/x/text/cases +golang.org/x/crypto/scrypt +google.golang.org/protobuf/internal/filetype +blitiri.com.ar/go/spf blitiri.com.ar/go/systemd +crypto/tls +blitiri.com.ar/go/chasquid/internal/dkim google.golang.org/protobuf/runtime/protoimpl -blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/config +golang.org/x/text/secure/precis +internal/profile +text/tabwriter +runtime/trace +runtime/pprof +os/signal +runtime/debug +blitiri.com.ar/go/chasquid/internal/normalize blitiri.com.ar/go/chasquid/internal/userdb +golang.org/x/term +blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/protoio/testpb -blitiri.com.ar/go/chasquid/internal/dkim -crypto/tls -net/http/httptrace -blitiri.com.ar/go/chasquid/internal/testlib +internal/sysinfo +testing net/smtp -net/http +blitiri.com.ar/go/chasquid/internal/testlib +net/http/httptrace blitiri.com.ar/go/chasquid/internal/smtp +net/http +blitiri.com.ar/go/chasquid/internal/nettrace +expvar golang.org/x/net/context/ctxhttp net/http/pprof -expvar -blitiri.com.ar/go/chasquid/internal/nettrace -blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/trace -blitiri.com.ar/go/chasquid/internal/domaininfo +blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/localrpc blitiri.com.ar/go/chasquid/internal/maillog +blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/auth -blitiri.com.ar/go/chasquid/internal/sts -blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/cmd/chasquid-util -blitiri.com.ar/go/chasquid/cmd/smtp-check +blitiri.com.ar/go/chasquid/internal/aliases +blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/courier +blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid make[1]: Leaving directory '/build/reproducible-path/chasquid-1.14.0' dh_auto_test -O--buildsystem=golang - cd obj-i686-linux-gnu && go test -vet=off -v -p 6 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/dkim 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/localrpc 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-i686-linux-gnu && go test -vet=off -v -p 5 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/dkim 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/localrpc 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] @@ -694,7 +730,7 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.017s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.030s === 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 @@ -707,11 +743,11 @@ auth_test.go:56: OK: "\xffa@b\x00\xffa@b\x00pass" failed with bidirule: failed Bidi Rule --- PASS: TestDecodeResponse (0.00s) === RUN TestAuthenticate ---- PASS: TestAuthenticate (0.35s) +--- PASS: TestAuthenticate (0.53s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.18s) +--- PASS: TestMultipleBackends (0.17s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload @@ -740,46 +776,46 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.548s +ok blitiri.com.ar/go/chasquid/internal/auth 0.728s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_283308344" + testlib.go:32: test directory: "/tmp/testlib_712983528" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_3707299033" + testlib.go:32: test directory: "/tmp/testlib_2076006541" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_694235653" + testlib.go:32: test directory: "/tmp/testlib_124874348" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_242954756" + testlib.go:32: test directory: "/tmp/testlib_104195028" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.007s +ok blitiri.com.ar/go/chasquid/internal/config 0.013s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_1468767268" + testlib.go:32: test directory: "/tmp/testlib_2743344194" --- PASS: TestMDA (0.00s) === RUN TestMDATimeout _ mda.go:73 Courier.MDA to@local: error: operation timed out ---- PASS: TestMDATimeout (0.10s) +--- PASS: TestMDATimeout (0.11s) === RUN TestMDABadCommandLine _ mda.go:86 Courier.MDA to: error: MDA delivery failed: exec: \"thisdoesnotexist\": executable file not found in $PATH - \"\" _ mda.go:86 Courier.MDA to: error: MDA delivery failed: exit status 1 - \"cat: unrecognized option '--fail_unknown_option'\\nTry 'cat --help' for more information.\\n\" ---- PASS: TestMDABadCommandLine (0.00s) +--- PASS: TestMDABadCommandLine (0.01s) === RUN TestExitCode mda_test.go: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_2083519843" - testlib.go:32: test directory: "/tmp/testlib_4147494068" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::34995: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::34995: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_1192340051" + testlib.go:32: test directory: "/tmp/testlib_3251540131" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::43979: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::43979: too many colons in address fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -787,26 +823,26 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestSMTP (0.14s) +--- PASS: TestSMTP (0.11s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_1274437222" - testlib.go:32: test directory: "/tmp/testlib_3403490066" + testlib.go:32: test directory: "/tmp/testlib_1472503440" + testlib.go:32: test directory: "/tmp/testlib_1345617162" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:37184->127.0.0.1:42983: 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:37184->127.0.0.1:42983: 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:37184->127.0.0.1:42983: 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:37184->127.0.0.1:42983: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:38654->127.0.0.1:45561: 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:38654->127.0.0.1:45561: 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:38654->127.0.0.1:45561: 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:38654->127.0.0.1:45561: i/o timeout) fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_1210386165" - testlib.go:32: test directory: "/tmp/testlib_2761222452" + testlib.go:32: test directory: "/tmp/testlib_4136746226" + testlib.go:32: test directory: "/tmp/testlib_1679219397" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" _ smtp.go:202 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:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_287255036" - testlib.go:32: test directory: "/tmp/testlib_2828803059" + smtp_test.go:140: failed as expected: MAIL+RCPT 501 mail error + testlib.go:32: test directory: "/tmp/testlib_647370796" + testlib.go:32: test directory: "/tmp/testlib_2795970482" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -814,8 +850,8 @@ _ smtp.go:202 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:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_3329678330" - testlib.go:32: test directory: "/tmp/testlib_288348317" + testlib.go:32: test directory: "/tmp/testlib_3194848582" + testlib.go:32: test directory: "/tmp/testlib_1817390781" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -824,8 +860,8 @@ _ smtp.go:207 Courier.SMTP to@to: error: DATA 554 data error fakeserver_test.go:132: fakeServer exiting: unexpected EOF smtp_test.go:140: failed as expected: DATA 554 data error - testlib.go:32: test directory: "/tmp/testlib_155491913" - testlib.go:32: test directory: "/tmp/testlib_4047788221" + testlib.go:32: test directory: "/tmp/testlib_2749758699" + testlib.go:32: test directory: "/tmp/testlib_1182175122" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -834,9 +870,9 @@ _ smtp.go:216 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:105: fakeServer exiting: EOF ---- PASS: TestSMTPErrors (1.60s) +--- PASS: TestSMTPErrors (1.66s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_1342432638" + testlib.go:32: test directory: "/tmp/testlib_3434697130" _ 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) @@ -851,8 +887,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_3722726914" - testlib.go:32: test directory: "/tmp/testlib_1189366041" + testlib.go:32: test directory: "/tmp/testlib_813585831" + testlib.go:32: test directory: "/tmp/testlib_2973053922" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -863,34 +899,34 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_312278535" + testlib.go:32: test directory: "/tmp/testlib_640078825" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" _ domaininfo.go:145 DomainInfo.Outgoing to: error: to outgoing denied: PLAIN < TLS_INSECURE _ smtp.go:185 Courier.SMTP to@to: error: Security level check failed (level:PLAIN) + fakeserver_test.go:105: fakeServer exiting: EOF _ 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:105: fakeServer exiting: EOF ---- PASS: TestTLS (0.19s) +--- PASS: TestTLS (0.17s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_3488664363" - testlib.go:32: test directory: "/tmp/testlib_315359348" + testlib.go:32: test directory: "/tmp/testlib_3710757739" + testlib.go:32: test directory: "/tmp/testlib_2882682961" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" _ smtp.go:171 Courier.SMTP to@to: error: TLS error, retrying without TLS: 500 starttls err - fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:105: fakeServer exiting: EOF + fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" fakeserver_test.go:109: fakeServer read: "RCPT TO:" fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestTLSError (0.13s) +--- PASS: TestTLSError (0.04s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_2624759544" - testlib.go:32: test directory: "/tmp/testlib_2373586631" + testlib.go:32: test directory: "/tmp/testlib_1289731964" + testlib.go:32: test directory: "/tmp/testlib_1457181877" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -898,7 +934,7 @@ _ domaininfo.go:153 DomainInfo.Outgoing to: to outgoing level raised: TLS_INSECURE > PLAIN _ smtp.go:194 test test: error: invalid security level (TLS_INSECURE) for STS policy fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_2474240928" + testlib.go:32: test directory: "/tmp/testlib_2370334318" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -909,9 +945,9 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestSTSPolicyEnforcement (0.23s) +--- PASS: TestSTSPolicyEnforcement (0.32s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.406s +ok blitiri.com.ar/go/chasquid/internal/courier 2.448s === RUN TestStringToCanonicalization --- PASS: TestStringToCanonicalization (0.00s) === RUN TestSimpleBody @@ -947,7 +983,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=ed25519; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa20b770 0xa20b7a0]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa9aa210 0xa9aa240]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -991,7 +1027,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=ed25519; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:5 Valid:5 Results:[0xa20bad0 0xa20bb00 0xa20bb30 0xa20bb60 0xa20bb90]} + file_test.go:56: Want result: &{Found:5 Valid:5 Results:[0xa816240 0xa816270 0xa8162a0 0xa8162d0 0xa816300]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1103,7 +1139,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=8; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xa2b60f0]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xaa38000]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=8; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1117,7 +1153,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[brisbane._domainkey.football.example.com:lookup : temporary error (for testing)] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xa2b6270 0xa2b62a0]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xaa381b0 0xaa381e0]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1151,7 +1187,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[brisbane._domainkey.football.example.com:lookup : permanent error (for testing)] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xa2b6510 0xa2b6540]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xaa38450 0xaa38480]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1185,7 +1221,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB", " v=DKIM1; k=ed25519; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa2b6780 0xa2b67b0]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xaa386c0 0xaa386f0]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1232,7 +1268,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=ed25519; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "selector._domainkey.example.com":[]string{" v=DKIM1; k=ed25519; p=SvoPT692bVrQBT8UNxt6SF538O3snA4fE3/i/glCxwQ="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xa2b6a50 0xa2b6a80 0xa2b6ab0]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xaa38990 0xaa389c0 0xaa389f0]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=selector; t=1709341950; @@ -1292,7 +1328,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=ed25519; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "selector._domainkey.example.com":[]string{" v=DKIM1; k=ed25519; p=SvoPT692bVrQBT8UNxt6SF538O3snA4fE3/i/glCxwQ="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xa2b6de0 0xa2b6e10 0xa2b6e40]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xaa38d20 0xaa38d50 0xaa38d80]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=selector; t=1709368031; @@ -1352,7 +1388,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"brisbane._domainkey.football.example.com":[]string{" v=DKIM1; k=ed25519; t=s; p=11qYAYKxCrfVS/7TyWQHOg7hcvPapiMlrwIaaPcHURo="}, "test._domainkey.football.example.com":[]string{" v=DKIM1; k=rsa; t=s; p=MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDkHlOQoBTzWRiGs5V6NpP3idY6Wk08a5qhdR6wy5bdOKb2jLQiY/J16JYi0Qvx/byYzCNb3W91y3FutACDfzwQ/BC/e/8uBsCR+yz1Lxj+PL6lHvqMKrM3rG4hstT5QjvHO9PzoxZyVYLzBfO2EeC3Ip3G+2kryOTIKT+l/K4w3QIDAQAB"}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa2b71d0 0xa2b7200]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xaa39110 0xaa39140]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=football.example.com; i=@football.example.com; @@ -1398,7 +1434,7 @@ file_test.go:50: Loaded DNS results: map[string][]string{"selector._domainkey.example.com":[]string{" v=DKIM1; k=ed25519; t=s; p=SvoPT692bVrQBT8UNxt6SF538O3snA4fE3/i/glCxwQ="}} file_test.go:50: Loaded DNS errors: map[] file_test.go:55: Message: "DKIM-Signature: v=1; a=ed25519-sha256; c=relaxed/relaxed;\r\n " - file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xa2b7470]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xaa393b0]} file_test.go:57: Want error: context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=selector; t=1709466347; @@ -1419,7 +1455,7 @@ context.go:17: Resulting hash: "kFTkVTyR9JWKX/Stl1+sUIuAgitRiOf7MwTBy5a2/ZA=" context.go:17: PK [ed25519:4afa0f4faf766d5a]: Strict domain check failed: "example.com" != "sub.example.com" ("test@sub.example.com") context.go:17: Found 1 signatures, 0 valid ---- PASS: TestFromFiles (0.03s) +--- PASS: TestFromFiles (0.05s) --- PASS: TestFromFiles/testdata/01-rfc8463 (0.01s) --- PASS: TestFromFiles/testdata/02-too_many_headers (0.01s) --- PASS: TestFromFiles/testdata/03-bad_message (0.00s) @@ -1428,7 +1464,7 @@ --- PASS: TestFromFiles/testdata/06-dns_perm_error (0.00s) --- PASS: TestFromFiles/testdata/07-algo_mismatch (0.00s) --- PASS: TestFromFiles/testdata/08-our_signature (0.00s) - --- PASS: TestFromFiles/testdata/09-limited_body (0.00s) + --- PASS: TestFromFiles/testdata/09-limited_body (0.01s) --- PASS: TestFromFiles/testdata/10-strict_domain_check_pass (0.00s) --- PASS: TestFromFiles/testdata/11-strict_domain_check_fail (0.00s) === RUN TestFromPrivateFiles @@ -1454,18 +1490,18 @@ context.go:17: Hashing header: "date:Fri, 11 Jul 2003 21:00:37 -0700 (PDT)\r\n" context.go:17: Hashing header: "to:Suzie Q \r\n" context.go:17: Hashing header: "message-id:<20030712040037.46341.5F8J@football.example.com>\r\n" - context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1733141042; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "BSjKAdxcXIZJmQbpvoM6f/USRihDiOBSManJ8o3zGgE=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "73zWCwqY+viw42T+1rp6i0jKg+sTM8iKF8TvMrwlTbI=" context.go:17: Found DKIM-Signature header: v=1; a=rsa-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1733141042; + d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=hHeyCnZFiNIawViFv9pBLu5fmiY8lYkJ9RYtByJVNP6KoFZYD20+6VURbE51qIswTvZAWx - p0xeTcZlZnerQJco1ePAnRe+IBsdIf7kUe8LHhWuyjxzPYW/YyP+S3D8StJx3WRv+zrxCP - gtdOCup+PxWcUE4S/jR/o3MRwnGVTvMyC/FZFP7EQwx4jd0UFASrYiOx1VDuAgDZrJ3gN7 - Sv9BxuXP/yjeUnRZc0nzEQUwu61OpXI5ZuyCe2h++57OLsWtNe8LScSxfYYBmkh4uiQLIr - D1Mnxr2PyjtQ/X8ocB8BdmGqMReWJ14UxFQDcuLj8UDRpp4QMtN4SJZVy0+r+w==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAt1ajxBtLFIoG2Os4SgUerlJCwxMBt+oiyrF+1XpcYt19O8+9LblK1WFcSxOSSgab8XIL0W4bbNo4IRL8aVZHNQdFpGmAk/RVv3kkripKSZRn/HmiQf8+U3MjfWFquDQrWTVnXhqdPq3h04zQt0AROojbUI7SwkOi0Vdk4ky1TvGxC0QVqrEKsJZSXQgYZEH8HoiMnPUcGSc9bzhU2OtMsjyMURpEpuvAceybKLFY+huOxr3VlNS0oEzReLqd+i1YNfHpI8HZ95lpvoFNSNtuJ7MQweVWGXTkOHzvN0gizKhtILAtPdlSKClEwUu8akHo5lW4hDp1bMbzGQNmrNzn/QIDAQAB" + b=vr/pVL1KL1uGELiTovIwXCrmFFlSDbet2jL9mjgPL6Z48QKBpFTlnnFiaaa8iS+DviKluQ + jTxhF33C3Nhu/+X0oRbJZ1SGM1rQad4iNNwfDVXFlyvWfLJCzWnKuJLqjE/7yZgDaJjv1t + ls2i4K0EUAbPR0syY9J0kkKUad0IYlckQ4ACGesShNAIlx5fFyaQxYZpTvH1Go/s5pao2+ + O4RVOW9+FusCm0TYysGOh4sBGnFdfwOrF8bLTiFHhKO9Fp1xDNdtvzZflVsU39Wfk1X011 + L6Xxenppq1q35hjYUgua0mwrrsah/RlTAh427Fr3HQbEfTY5DwkQNpcvXJUBMA==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAzSkcFrsKNehbY7k0ARefNWxT/uAAQlcHwKb0pW37hU36LuIc0GmVllsbOq5g8WqVHGvY7u3dDobwJ2H/n37QhOHw4pGDaDpQfZI2Ibwibf/SjYH8YMrlDq/mrU60661TLSkk1LVKAYV56rbGbx3CtsZ2m8cdyaMhbSLWv2XBH2fjtPDlZyxHupHxLkBIq+Ja5ik7Th40gDu/b1VQ50DOlEMbYTe6WcxmxK5ptX14h7soI1F0KRicEJFXL5/ZLZusJ2N+ubcJtMZGPyuQp6BHeiyXrX/rk8/hP3k6jqk/y0wV1WaPhgoRwbRbkbCltvsBpmIN032tIzPdlMk+vrMJ4QIDAQAB" context.go:17: Parsed public key: [rsa:30820122300d0609] context.go:17: Body hash matches: "2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=" context.go:17: Hashing header: "from:Joe SixPack \r\n" @@ -1473,11 +1509,11 @@ context.go:17: Hashing header: "date:Fri, 11 Jul 2003 21:00:37 -0700 (PDT)\r\n" context.go:17: Hashing header: "to:Suzie Q \r\n" context.go:17: Hashing header: "message-id:<20030712040037.46341.5F8J@football.example.com>\r\n" - context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1733141042; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "BSjKAdxcXIZJmQbpvoM6f/USRihDiOBSManJ8o3zGgE=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "73zWCwqY+viw42T+1rp6i0jKg+sTM8iKF8TvMrwlTbI=" context.go:17: PK [rsa:30820122300d0609]: Verification succeeded context.go:17: Found 1 signatures, 1 valid ---- PASS: TestSignRSA (2.58s) +--- PASS: TestSignRSA (4.14s) === RUN TestSignEd25519 context.go:17: Signing for example.com / test with ed25519-sha256 context.go:17: Hashing header: "from:Joe SixPack \r\n" @@ -1485,25 +1521,25 @@ context.go:17: Hashing header: "date:Fri, 11 Jul 2003 21:00:37 -0700 (PDT)\r\n" context.go:17: Hashing header: "to:Suzie Q \r\n" context.go:17: Hashing header: "message-id:<20030712040037.46341.5F8J@football.example.com>\r\n" - context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1733141042; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "z4b6Cjhrf5Sk2ltlH4PHAlf4V+3ddrOg25W1b67EU5Q=" + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "beM8jemwZ1PyPFZYhBsLo9SeiWyrAtZEHUU8X6pkzyI=" context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1733141042; + d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=WjBP+NDgRqyP1q92FAOReEP2q2PPwMkUDdooIaSOWnSHNEI6lz45BEm4AUVDRZxsZ9Wtqk - PMhKiPBM+tNXEVDg==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=RRIFRvg+p4bAbLo7AJXYlEFzAW8fjatcTI3lWvzuRI8=" - context.go:17: Parsed public key: [ed25519:45120546f83ea786] + b=lhqRC0j3P79a2rPA+U9XUqAc/TsUKY+sB+2kI+9Jo/QgkyQg3HmTB0DoeC2QRgIR9/2MfS + 5/mxVQ0ESa+f6JAw==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=EBqyT0y9hxWERyaoMK43hksXTdeR65UN6kiQQphkQIs=" + context.go:17: Parsed public key: [ed25519:101ab24f4cbd8715] context.go:17: Body hash matches: "2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=" context.go:17: Hashing header: "from:Joe SixPack \r\n" context.go:17: Hashing header: "subject:Is dinner ready?\r\n" context.go:17: Hashing header: "date:Fri, 11 Jul 2003 21:00:37 -0700 (PDT)\r\n" context.go:17: Hashing header: "to:Suzie Q \r\n" context.go:17: Hashing header: "message-id:<20030712040037.46341.5F8J@football.example.com>\r\n" - context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1733141042; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "z4b6Cjhrf5Sk2ltlH4PHAlf4V+3ddrOg25W1b67EU5Q=" - context.go:17: PK [ed25519:45120546f83ea786]: Verification succeeded + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1767551327; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "beM8jemwZ1PyPFZYhBsLo9SeiWyrAtZEHUU8X6pkzyI=" + context.go:17: PK [ed25519:101ab24f4cbd8715]: Verification succeeded context.go:17: Found 1 signatures, 1 valid --- PASS: TestSignEd25519 (0.02s) === RUN TestSignBadMessage @@ -1702,25 +1738,25 @@ --- PASS: FuzzParsePublicKey/seed#12 (0.00s) --- PASS: FuzzParsePublicKey/seed#13 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dkim 2.653s +ok blitiri.com.ar/go/chasquid/internal/dkim 4.233s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1744376128" + testlib.go:32: test directory: "/tmp/testlib_1551498422" _ domaininfo.go:117 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:109 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:153 DomainInfo.Outgoing d1: d1 outgoing level raised: TLS_SECURE > PLAIN _ domaininfo.go:145 DomainInfo.Outgoing d1: error: d1 outgoing denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:109 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:182 DomainInfo.SetToPlain d1: set to plain ---- PASS: TestBasic (0.00s) +--- PASS: TestBasic (0.01s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_1837356466" + testlib.go:32: test directory: "/tmp/testlib_3618243841" _ domaininfo.go:153 DomainInfo.Outgoing insecure: insecure outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:117 DomainInfo.Incoming insecure: insecure incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:153 DomainInfo.Outgoing secure: secure outgoing level raised: TLS_SECURE > PLAIN _ domaininfo.go:117 DomainInfo.Incoming secure: secure incoming level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_2879850055" + testlib.go:32: test directory: "/tmp/testlib_3657145571" _ domaininfo.go:117 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:153 DomainInfo.Outgoing pisis: pisis outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:117 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_SECURE > TLS_INSECURE @@ -1735,27 +1771,27 @@ _ domaininfo.go:145 DomainInfo.Outgoing ssip: error: ssip outgoing denied: PLAIN < TLS_SECURE --- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_3852581412" + testlib.go:32: test directory: "/tmp/testlib_3211431676" _ domaininfo.go:117 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto:\u00a0syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto:\u00a0syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents --- PASS: TestErrors (0.00s) === RUN TestDirectoryErrors - testlib.go:32: test directory: "/tmp/testlib_3490005386" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_3490005386/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_3490005386/db/.s:d13698322963: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_837386347" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_837386347/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_837386347/db/.s:d1882584623: no such file or directory --- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.019s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.024s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_2598638410" ---- PASS: TestAutodetect (0.00s) + testlib.go:32: test directory: "/tmp/testlib_2895781421" +--- PASS: TestAutodetect (0.01s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.006s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.020s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -1763,13 +1799,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.003s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.012s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.005s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.007s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -1794,21 +1830,21 @@ 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.005s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.008s === RUN TestBadServer - asm_386.s:1393: FakeServer &{{0x8912500}}: accepted - asm_386.s:1393: FakeServer &{{0x8912500}}: readRequest: "" "" / EOF - asm_386.s:1393: FakeServer &{{0x8912500}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-1677258342/rpc.sock->@: write: broken pipe - asm_386.s:1393: FakeServer &{{0x8912500}}: closing - asm_386.s:1393: FakeServer &{{0x89125a0}}: accepted - asm_386.s:1393: FakeServer &{{0x89125a0}}: readRequest: "Echo" "" / - asm_386.s:1393: FakeServer &{{0x89125a0}}: writeMessage("xxx"): 3 - asm_386.s:1393: FakeServer &{{0x89125a0}}: closing + asm_386.s:1393: FakeServer &{{0x9d12500}}: accepted + asm_386.s:1393: FakeServer &{{0x9d12500}}: readRequest: "" "" / EOF + asm_386.s:1393: FakeServer &{{0x9d12500}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-3967581952/rpc.sock->@: write: broken pipe + asm_386.s:1393: FakeServer &{{0x9d12500}}: closing + asm_386.s:1393: FakeServer &{{0x9d125a0}}: accepted + asm_386.s:1393: FakeServer &{{0x9d125a0}}: readRequest: "Echo" "" / + asm_386.s:1393: FakeServer &{{0x9d125a0}}: writeMessage("xxx"): 3 + asm_386.s:1393: FakeServer &{{0x9d125a0}}: closing --- PASS: TestBadServer (0.01s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-3192366439/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-3219154615/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1816,8 +1852,8 @@ _ localrpc.go:139 LocalRPC.Handle @: error: -> 500 test error === RUN TestEndToEnd/UnknownMethod _ localrpc.go:139 LocalRPC.Handle @: error: -> 500 unknown method -_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-3192366439/sock: error: Accept error: accept unix /tmp/rpc-test-3192366439/sock: use of closed network connection ---- PASS: TestEndToEnd (0.01s) +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-3219154615/sock: error: Accept error: accept unix /tmp/rpc-test-3219154615/sock: use of closed network connection +--- PASS: TestEndToEnd (0.02s) --- PASS: TestEndToEnd/Echo (0.00s) --- PASS: TestEndToEnd/Echo#01 (0.00s) --- PASS: TestEndToEnd/Hola (0.00s) @@ -1831,7 +1867,7 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.029s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.041s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -1839,7 +1875,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.005s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.017s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1847,13 +1883,13 @@ === RUN TestHistogramEmpty --- PASS: TestHistogramEmpty (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.01s) +--- PASS: TestHTTP (0.02s) === RUN TestHTTPLong ---- PASS: TestHTTPLong (0.00s) +--- PASS: TestHTTPLong (0.01s) === RUN TestHTTPErrors --- PASS: TestHTTPErrors (0.01s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.01s) +--- PASS: TestHTTPUroboro (0.02s) === RUN TestHTTPDeep --- PASS: TestHTTPDeep (0.01s) === RUN TestStripZeros @@ -1875,7 +1911,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.043s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.101s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1935,40 +1971,40 @@ --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.010s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.012s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_1247892556" + testlib.go:32: test directory: "/tmp/testlib_2397156265" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_3859392444" + testlib.go:32: test directory: "/tmp/testlib_4256536580" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_711383452" + testlib.go:32: test directory: "/tmp/testlib_763278127" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_1095301027" + testlib.go:32: test directory: "/tmp/testlib_1920553927" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_2981558614" + testlib.go:32: test directory: "/tmp/testlib_2988639870" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.029s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.014s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Mon, 02 Dec 2024 12:04:01 +0000 + Message-ID: + Date: Sun, 04 Jan 2026 18:28:46 +0000 In-Reply-To: References: X-Failed-Recipients: pepe@africa.org, ñaca@africa.org, Auto-Submitted: auto-replied MIME-Version: 1.0 Content-Type: multipart/report; report-type=delivery-status; - boundary="l-ZfcI7K7ts" + boundary="eVCduqw6Fkc" - --l-ZfcI7K7ts + --eVCduqw6Fkc Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1993,7 +2029,7 @@ oh! the humanity! - --l-ZfcI7K7ts + --eVCduqw6Fkc Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -2024,7 +2060,7 @@ - --l-ZfcI7K7ts + --eVCduqw6Fkc Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -2034,78 +2070,76 @@ Data ñaca. - --l-ZfcI7K7ts-- + --eVCduqw6Fkc-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_555589981" -_ queue.go:308 Queue.SendLoop Vc1CX9f-GnA: from from -_ queue.go:367 Queue.SendLoop Vc1CX9f-GnA: nodomain sent -_ queue.go:367 Queue.SendLoop Vc1CX9f-GnA: x@remote sent -_ queue.go:367 Queue.SendLoop Vc1CX9f-GnA: am@loco sent -_ queue.go:342 Queue.SendLoop Vc1CX9f-GnA: all done + testlib.go:32: test directory: "/tmp/testlib_579063375" +_ queue.go:308 Queue.SendLoop 7VtR59PZnZg: from from +_ queue.go:367 Queue.SendLoop 7VtR59PZnZg: nodomain sent +_ queue.go:367 Queue.SendLoop 7VtR59PZnZg: am@loco sent +_ queue.go:367 Queue.SendLoop 7VtR59PZnZg: x@remote sent +_ queue.go:342 Queue.SendLoop 7VtR59PZnZg: all done --- PASS: TestBasic (0.02s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_1470111819" -_ queue.go:308 Queue.SendLoop 6s1GtGTLSkQ: from from@loco -_ queue.go:467 Queue.SendLoop 6s1GtGTLSkQ: queued DSN: YF5WaOKP9tU -_ queue.go:342 Queue.SendLoop 6s1GtGTLSkQ: all done -_ queue.go:308 Queue.SendLoop YF5WaOKP9tU: from <> -_ queue.go:367 Queue.SendLoop YF5WaOKP9tU: from@loco sent -_ queue.go:342 Queue.SendLoop YF5WaOKP9tU: all done + testlib.go:32: test directory: "/tmp/testlib_4041251720" +_ queue.go:308 Queue.SendLoop EZxWsIA-dEg: from from@loco +_ queue.go:467 Queue.SendLoop EZxWsIA-dEg: queued DSN: a3CI87O6gGM +_ queue.go:342 Queue.SendLoop EZxWsIA-dEg: all done +_ queue.go:308 Queue.SendLoop a3CI87O6gGM: from <> +_ queue.go:367 Queue.SendLoop a3CI87O6gGM: from@loco sent +_ queue.go:342 Queue.SendLoop a3CI87O6gGM: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_1800306027" -_ queue.go:308 Queue.SendLoop gv0noSjRVh4: from from -_ queue.go:367 Queue.SendLoop gv0noSjRVh4: ata@hualpa sent -_ queue.go:367 Queue.SendLoop gv0noSjRVh4: pq@loco sent -_ queue.go:367 Queue.SendLoop gv0noSjRVh4: rs@loco sent + testlib.go:32: test directory: "/tmp/testlib_3095047057" +_ queue.go:308 Queue.SendLoop M40HPrJilBM: from from +_ queue.go:367 Queue.SendLoop M40HPrJilBM: ata@hualpa sent +_ queue.go:367 Queue.SendLoop M40HPrJilBM: pq@loco sent +_ queue.go:367 Queue.SendLoop M40HPrJilBM: rs@loco sent +_ queue.go:342 Queue.SendLoop M40HPrJilBM: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_4768492" + testlib.go:32: test directory: "/tmp/testlib_4136949658" _ queue.go:164 Queue.Put from: error: queue full --- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_3167480403" -_ queue.go:308 Queue.SendLoop 7ymvQY0K36o: from from -_ queue.go:367 Queue.SendLoop 7ymvQY0K36o: to sent -_ queue.go:375 Queue.SendLoop 7ymvQY0K36o: error: failed to write: open /tmp/testlib_4768492/.m:7ymvQY0K36o1197975542: no such file or directory -_ queue.go:342 Queue.SendLoop 7ymvQY0K36o: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_4768492/m:7ymvQY0K36o": remove /tmp/testlib_4768492/m:7ymvQY0K36o: no such file or directory -_ queue.go:375 Queue.SendLoop gv0noSjRVh4: error: failed to write: rename /tmp/testlib_1800306027/.m:gv0noSjRVh42294833361 /tmp/testlib_1800306027/m:gv0noSjRVh4: no such file or directory -_ queue.go:342 Queue.SendLoop gv0noSjRVh4: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_1800306027/m:gv0noSjRVh4": remove /tmp/testlib_1800306027/m:gv0noSjRVh4: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_2333945636" +_ queue.go:308 Queue.SendLoop DMvwMacqXVg: from from +_ queue.go:367 Queue.SendLoop DMvwMacqXVg: to sent +_ queue.go:375 Queue.SendLoop DMvwMacqXVg: error: failed to write: open /tmp/testlib_4136949658/.m:DMvwMacqXVg2027420358: no such file or directory +_ queue.go:342 Queue.SendLoop DMvwMacqXVg: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_4136949658/m:DMvwMacqXVg": remove /tmp/testlib_4136949658/m:DMvwMacqXVg: no such file or directory --- PASS: TestPipes (0.00s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_3575826839" -_ queue.go:308 Queue.SendLoop Y3ADBoiKnBM: from from@loco -_ queue.go:367 Queue.SendLoop Y3ADBoiKnBM: to@to sent -_ queue.go:342 Queue.SendLoop Y3ADBoiKnBM: all done ---- PASS: TestSerialization (0.00s) + testlib.go:32: test directory: "/tmp/testlib_1685654126" +_ queue.go:308 Queue.SendLoop dZVFyi41SCI: from from@loco +_ queue.go:367 Queue.SendLoop dZVFyi41SCI: to@to sent +_ queue.go:342 Queue.SendLoop dZVFyi41SCI: all done +--- PASS: TestSerialization (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.042s +ok blitiri.com.ar/go/chasquid/internal/queue 0.048s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_1857965944" + testlib.go:32: test directory: "/tmp/testlib_1233763235" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_2766556470" + testlib.go:32: test directory: "/tmp/testlib_3980319520" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_3125973098" + testlib.go:32: test directory: "/tmp/testlib_3277353256" --- PASS: TestWriteFileWithFailingOp (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_78106834" + testlib.go:32: test directory: "/tmp/testlib_2033875249" --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.008s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.036s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.012s +ok blitiri.com.ar/go/chasquid/internal/set 0.009s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -2121,19 +2155,19 @@ === RUN TestLineTooLong --- PASS: TestLineTooLong (0.04s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.047s +ok blitiri.com.ar/go/chasquid/internal/smtp 0.048s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] -_ server.go:296 Server listening on 127.0.0.1:35151 (SMTP) -2024-12-02 12:04:02.594366 daemon listening on 127.0.0.1:35151 -_ server.go:296 Server listening on 127.0.0.1:41779 (submission) -2024-12-02 12:04:02.594445 daemon listening on 127.0.0.1:41779 -_ server.go:296 Server listening on 127.0.0.1:43093 (submission+TLS) -2024-12-02 12:04:02.594506 daemon listening on 127.0.0.1:43093 +_ server.go:296 Server listening on 127.0.0.1:39933 (SMTP) +2026-01-04 18:28:48.532704 daemon listening on 127.0.0.1:39933 +_ server.go:296 Server listening on 127.0.0.1:39273 (submission) +2026-01-04 18:28:48.532786 daemon listening on 127.0.0.1:39273 +_ server.go:296 Server listening on 127.0.0.1:33619 (submission+TLS) +2026-01-04 18:28:48.532814 daemon listening on 127.0.0.1:33619 === RUN TestSecLevel -_ conn.go:209 SMTP.Conn 127.0.0.1:46658: error: error completing TLS handshake: EOF _ domaininfo.go:117 DomainInfo.Incoming slc: slc incoming level raised: TLS_CLIENT > PLAIN _ domaininfo.go:109 DomainInfo.Incoming slc: error: slc incoming denied: PLAIN < TLS_CLIENT _ conn.go:559 testconn testconn: error: security level check for slc failed (PLAIN) +_ conn.go:209 SMTP.Conn 127.0.0.1:39644: error: error completing TLS handshake: EOF --- PASS: TestSecLevel (0.00s) === RUN TestIsHeader --- PASS: TestIsHeader (0.00s) @@ -2146,182 +2180,182 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:722 SMTP.Conn 127.0.0.1:45840: Queued from from@from to [to@localhost] - 7pQ7sVV7230 -2024-12-02 12:04:02.700106 7pQ7sVV7230 from=from@from queued ip=127.0.0.1:45840 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 7pQ7sVV7230: from from@from -_ queue.go:367 Queue.SendLoop 7pQ7sVV7230: testuser@localhost sent -2024-12-02 12:04:02.700271 7pQ7sVV7230 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 7pQ7sVV7230: all done -2024-12-02 12:04:02.700556 7pQ7sVV7230 from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:53130: Queued from from@from to [to@localhost] - q65PZFEeIt8 +_ queue.go:308 Queue.SendLoop q65PZFEeIt8: from from@from +2026-01-04 18:28:48.637725 q65PZFEeIt8 from=from@from queued ip=127.0.0.1:53130 to=[to@localhost] +_ queue.go:367 Queue.SendLoop q65PZFEeIt8: testuser@localhost sent --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ conn.go:722 SMTP.Conn 127.0.0.1:45856: Queued from from@from to [to@localhost] - av8CllCuLvs -2024-12-02 12:04:02.708636 av8CllCuLvs from=from@from queued ip=127.0.0.1:45856 to=[to@localhost] -_ queue.go:308 Queue.SendLoop av8CllCuLvs: from from@from -_ queue.go:367 Queue.SendLoop av8CllCuLvs: testuser@localhost sent -2024-12-02 12:04:02.708718 av8CllCuLvs from=from@from to=testuser@localhost sent +2026-01-04 18:28:48.638023 q65PZFEeIt8 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop q65PZFEeIt8: all done +2026-01-04 18:28:48.638324 q65PZFEeIt8 from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:53138: Queued from from@from to [to@localhost] - tqOB_b36bqk +2026-01-04 18:28:48.649745 tqOB_b36bqk from=from@from queued ip=127.0.0.1:53138 to=[to@localhost] +_ queue.go:308 Queue.SendLoop tqOB_b36bqk: from from@from +_ queue.go:367 Queue.SendLoop tqOB_b36bqk: testuser@localhost sent +2026-01-04 18:28:48.649833 tqOB_b36bqk from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop tqOB_b36bqk: all done +2026-01-04 18:28:48.650017 tqOB_b36bqk from=from@from all done --- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ queue.go:342 Queue.SendLoop av8CllCuLvs: all done -2024-12-02 12:04:02.708952 av8CllCuLvs from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:45868: Queued from from@from to [to@localhost] - 5rFXHumfWwY -2024-12-02 12:04:02.716357 5rFXHumfWwY from=from@from queued ip=127.0.0.1:45868 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 5rFXHumfWwY: from from@from -_ queue.go:367 Queue.SendLoop 5rFXHumfWwY: testuser@localhost sent -2024-12-02 12:04:02.716442 5rFXHumfWwY from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 5rFXHumfWwY: all done -2024-12-02 12:04:02.716690 5rFXHumfWwY from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:45868: Queued from from@from to [to@localhost] - Sf78WqWDf50 -2024-12-02 12:04:02.717534 Sf78WqWDf50 from=from@from queued ip=127.0.0.1:45868 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Sf78WqWDf50: from from@from -_ queue.go:367 Queue.SendLoop Sf78WqWDf50: testuser@localhost sent -2024-12-02 12:04:02.717617 Sf78WqWDf50 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop Sf78WqWDf50: all done -2024-12-02 12:04:02.718016 Sf78WqWDf50 from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:45868: Queued from from@from to [to@localhost] - jxxpFvS-yD8 -2024-12-02 12:04:02.718115 jxxpFvS-yD8 from=from@from queued ip=127.0.0.1:45868 to=[to@localhost] -_ queue.go:308 Queue.SendLoop jxxpFvS-yD8: from from@from -_ queue.go:367 Queue.SendLoop jxxpFvS-yD8: testuser@localhost sent -2024-12-02 12:04:02.718205 jxxpFvS-yD8 from=from@from to=testuser@localhost sent +_ conn.go:722 SMTP.Conn 127.0.0.1:53152: Queued from from@from to [to@localhost] - HDfDeDVjXl4 +2026-01-04 18:28:48.658555 HDfDeDVjXl4 from=from@from queued ip=127.0.0.1:53152 to=[to@localhost] +_ queue.go:308 Queue.SendLoop HDfDeDVjXl4: from from@from +_ queue.go:367 Queue.SendLoop HDfDeDVjXl4: testuser@localhost sent +2026-01-04 18:28:48.658625 HDfDeDVjXl4 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop HDfDeDVjXl4: all done +2026-01-04 18:28:48.658810 HDfDeDVjXl4 from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:53152: Queued from from@from to [to@localhost] - _zEe8nGBa1g +2026-01-04 18:28:48.659157 _zEe8nGBa1g from=from@from queued ip=127.0.0.1:53152 to=[to@localhost] +_ queue.go:308 Queue.SendLoop _zEe8nGBa1g: from from@from +_ queue.go:367 Queue.SendLoop _zEe8nGBa1g: testuser@localhost sent +2026-01-04 18:28:48.659209 _zEe8nGBa1g from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop _zEe8nGBa1g: all done +2026-01-04 18:28:48.659353 _zEe8nGBa1g from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:53152: Queued from from@from to [to@localhost] - dVfWnLpKSbg +2026-01-04 18:28:48.660398 dVfWnLpKSbg from=from@from queued ip=127.0.0.1:53152 to=[to@localhost] +_ queue.go:308 Queue.SendLoop dVfWnLpKSbg: from from@from +_ queue.go:367 Queue.SendLoop dVfWnLpKSbg: testuser@localhost sent +2026-01-04 18:28:48.660445 dVfWnLpKSbg from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop dVfWnLpKSbg: all done +2026-01-04 18:28:48.660598 dVfWnLpKSbg from=from@from all done --- PASS: TestManyEmails (0.01s) === RUN TestAuth -_ queue.go:342 Queue.SendLoop jxxpFvS-yD8: all done -2024-12-02 12:04:02.718839 jxxpFvS-yD8 from=from@from all done -2024-12-02 12:04:02.832805 127.0.0.1:42320 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:42320: Queued from testuser@localhost to [to@localhost] - PJqLrdz5xJM -2024-12-02 12:04:02.833841 PJqLrdz5xJM from=testuser@localhost queued ip=127.0.0.1:42320 to=[to@localhost] -_ queue.go:308 Queue.SendLoop PJqLrdz5xJM: from testuser@localhost -_ queue.go:367 Queue.SendLoop PJqLrdz5xJM: testuser@localhost sent -2024-12-02 12:04:02.834010 PJqLrdz5xJM from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop PJqLrdz5xJM: all done +2026-01-04 18:28:48.776083 127.0.0.1:38074 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:38074: Queued from testuser@localhost to [to@localhost] - bUfqFfsILTc +2026-01-04 18:28:48.776745 bUfqFfsILTc from=testuser@localhost queued ip=127.0.0.1:38074 to=[to@localhost] +_ queue.go:308 Queue.SendLoop bUfqFfsILTc: from testuser@localhost +_ queue.go:367 Queue.SendLoop bUfqFfsILTc: testuser@localhost sent +2026-01-04 18:28:48.776863 bUfqFfsILTc from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop bUfqFfsILTc: all done +2026-01-04 18:28:48.777099 bUfqFfsILTc from=testuser@localhost all done --- PASS: TestAuth (0.12s) -2024-12-02 12:04:02.834337 PJqLrdz5xJM from=testuser@localhost all done === RUN TestSubmissionWithoutAuth -_ conn.go:318 SMTP.Conn 127.0.0.1:42332: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ conn.go:318 SMTP.Conn 127.0.0.1:38088: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.01s) === RUN TestAuthOnTLS -2024-12-02 12:04:02.954410 127.0.0.1:46660 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:46660: Queued from testuser@localhost to [to@localhost] - oS_Si28oZis -2024-12-02 12:04:02.955242 oS_Si28oZis from=testuser@localhost queued ip=127.0.0.1:46660 to=[to@localhost] -_ queue.go:308 Queue.SendLoop oS_Si28oZis: from testuser@localhost -_ queue.go:367 Queue.SendLoop oS_Si28oZis: testuser@localhost sent -2024-12-02 12:04:02.955432 oS_Si28oZis from=testuser@localhost to=testuser@localhost sent +2026-01-04 18:28:48.895372 127.0.0.1:39658 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:39658: Queued from testuser@localhost to [to@localhost] - r3heFNHPvlY +2026-01-04 18:28:48.895999 r3heFNHPvlY from=testuser@localhost queued ip=127.0.0.1:39658 to=[to@localhost] +_ queue.go:308 Queue.SendLoop r3heFNHPvlY: from testuser@localhost +_ queue.go:367 Queue.SendLoop r3heFNHPvlY: testuser@localhost sent +2026-01-04 18:28:48.896062 r3heFNHPvlY from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop r3heFNHPvlY: all done +2026-01-04 18:28:48.896261 r3heFNHPvlY from=testuser@localhost all done --- PASS: TestAuthOnTLS (0.11s) === RUN TestAuthOnSMTP -_ queue.go:342 Queue.SendLoop oS_Si28oZis: all done -2024-12-02 12:04:02.955727 oS_Si28oZis from=testuser@localhost all done -2024-12-02 12:04:03.064133 127.0.0.1:45884 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:45884: Queued from testuser@localhost to [to@localhost] - ibIR9r8lKtk -2024-12-02 12:04:03.065066 ibIR9r8lKtk from=testuser@localhost queued ip=127.0.0.1:45884 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ibIR9r8lKtk: from testuser@localhost -_ queue.go:367 Queue.SendLoop ibIR9r8lKtk: testuser@localhost sent -2024-12-02 12:04:03.065182 ibIR9r8lKtk from=testuser@localhost to=testuser@localhost sent +2026-01-04 18:28:49.006910 127.0.0.1:53158 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:53158: Queued from testuser@localhost to [to@localhost] - MwJEzT9KEhk +2026-01-04 18:28:49.007778 MwJEzT9KEhk from=testuser@localhost queued ip=127.0.0.1:53158 to=[to@localhost] +_ queue.go:308 Queue.SendLoop MwJEzT9KEhk: from testuser@localhost +_ queue.go:367 Queue.SendLoop MwJEzT9KEhk: testuser@localhost sent +2026-01-04 18:28:49.007841 MwJEzT9KEhk from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop MwJEzT9KEhk: all done +2026-01-04 18:28:49.008059 MwJEzT9KEhk from=testuser@localhost all done --- PASS: TestAuthOnSMTP (0.11s) -_ queue.go:342 Queue.SendLoop ibIR9r8lKtk: all done === RUN TestBrokenAuth -2024-12-02 12:04:03.065445 ibIR9r8lKtk from=testuser@localhost all done -_ conn.go:1193 SMTP.Conn 127.0.0.1:42340: error: error authenticating \"user\"@\"broken\": failed to auth -2024-12-02 12:04:03.190192 127.0.0.1:42340 auth failed for user@broken -_ conn.go:318 SMTP.Conn 127.0.0.1:42340: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:318 SMTP.Conn 127.0.0.1:42340: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.13s) +_ conn.go:1193 SMTP.Conn 127.0.0.1:38096: error: error authenticating \"user\"@\"broken\": failed to auth +2026-01-04 18:28:49.131528 127.0.0.1:38096 auth failed for user@broken +_ conn.go:318 SMTP.Conn 127.0.0.1:38096: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:318 SMTP.Conn 127.0.0.1:38096: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:318 SMTP.Conn 127.0.0.1:45892: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45892: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45892: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45892: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:45900: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45900: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45900: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45900: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:45902: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45902: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45902: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45902: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:45918: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45918: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45918: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45918: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:45926: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45926: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45926: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45926: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:45940: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:45940: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:45940: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:45940: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.00s) +_ conn.go:318 SMTP.Conn 127.0.0.1:53164: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53164: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53164: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53164: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:53180: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53180: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53180: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53180: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:53194: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53194: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53194: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53194: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:53206: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53206: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53206: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53206: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:53216: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53216: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53216: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53216: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:53224: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:53224: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:53224: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:53224: error: too many errors, breaking connection +--- PASS: TestWrongMailParsing (0.01s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:318 SMTP.Conn 127.0.0.1:45950: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:318 SMTP.Conn 127.0.0.1:53240: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.01s) === RUN TestRelayForbidden -2024-12-02 12:04:03.203755 127.0.0.1:45970 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:318 SMTP.Conn 127.0.0.1:45970: error: RCPT failed: 503 5.7.1 Relay not allowed +2026-01-04 18:28:49.145785 127.0.0.1:53252 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:318 SMTP.Conn 127.0.0.1:53252: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2024-12-02 12:04:03.311651 127.0.0.1:42352 auth succeeded for testuser@localhost -_ conn.go:318 SMTP.Conn 127.0.0.1:42352: error: RCPT failed: 452 4.5.3 Too many recipients +2026-01-04 18:28:49.256521 127.0.0.1:38106 auth succeeded for testuser@localhost +_ conn.go:318 SMTP.Conn 127.0.0.1:38106: error: RCPT failed: 452 4.5.3 Too many recipients --- PASS: TestTooManyRecipients (0.11s) === RUN TestRcptBrokenExists -_ conn.go:622 SMTP.Conn 127.0.0.1:45972: error: error checking if user \"to@broken\" exists: failed to check if user exists -2024-12-02 12:04:03.325149 127.0.0.1:45972 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists -_ conn.go:318 SMTP.Conn 127.0.0.1:45972: error: RCPT failed: 451 4.4.3 Temporary error checking address +_ conn.go:622 SMTP.Conn 127.0.0.1:53262: error: error checking if user \"to@broken\" exists: failed to check if user exists +2026-01-04 18:28:49.265873 127.0.0.1:53262 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists +_ conn.go:318 SMTP.Conn 127.0.0.1:53262: error: RCPT failed: 451 4.4.3 Temporary error checking address --- PASS: TestRcptBrokenExists (0.01s) === RUN TestRcptUserDoesNotExist -2024-12-02 12:04:03.332073 127.0.0.1:45982 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:318 SMTP.Conn 127.0.0.1:45982: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +2026-01-04 18:28:49.277598 127.0.0.1:53278 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:318 SMTP.Conn 127.0.0.1:53278: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptUserDoesNotExist (0.01s) === RUN TestTooMuchData -_ conn.go:722 SMTP.Conn 127.0.0.1:45994: Queued from from@from to [to@localhost] - wKpzc1UbZZo -2024-12-02 12:04:03.522848 wKpzc1UbZZo from=from@from queued ip=127.0.0.1:45994 to=[to@localhost] -_ queue.go:308 Queue.SendLoop wKpzc1UbZZo: from from@from -_ queue.go:367 Queue.SendLoop wKpzc1UbZZo: testuser@localhost sent -2024-12-02 12:04:03.523058 wKpzc1UbZZo from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop wKpzc1UbZZo: all done -2024-12-02 12:04:03.533055 wKpzc1UbZZo from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:45994: Queued from from@from to [to@localhost] - vW_mpC0zso4 -2024-12-02 12:04:03.697705 vW_mpC0zso4 from=from@from queued ip=127.0.0.1:45994 to=[to@localhost] -_ queue.go:308 Queue.SendLoop vW_mpC0zso4: from from@from -_ queue.go:367 Queue.SendLoop vW_mpC0zso4: testuser@localhost sent -2024-12-02 12:04:03.697837 vW_mpC0zso4 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop vW_mpC0zso4: all done -2024-12-02 12:04:03.709979 vW_mpC0zso4 from=from@from all done -_ conn.go:318 SMTP.Conn 127.0.0.1:45994: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:722 SMTP.Conn 127.0.0.1:45994: Queued from from@from to [to@localhost] - jPwc-9xBRCA -2024-12-02 12:04:04.087715 jPwc-9xBRCA from=from@from queued ip=127.0.0.1:45994 to=[to@localhost] -_ queue.go:308 Queue.SendLoop jPwc-9xBRCA: from from@from -_ queue.go:367 Queue.SendLoop jPwc-9xBRCA: testuser@localhost sent -2024-12-02 12:04:04.087908 jPwc-9xBRCA from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.76s) +_ conn.go:722 SMTP.Conn 127.0.0.1:53286: Queued from from@from to [to@localhost] - 4mNOoZslTPw +2026-01-04 18:28:49.522160 4mNOoZslTPw from=from@from queued ip=127.0.0.1:53286 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 4mNOoZslTPw: from from@from +_ queue.go:367 Queue.SendLoop 4mNOoZslTPw: testuser@localhost sent +2026-01-04 18:28:49.522306 4mNOoZslTPw from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 4mNOoZslTPw: all done +2026-01-04 18:28:49.550002 4mNOoZslTPw from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:53286: Queued from from@from to [to@localhost] - eGaQ1L5pcF8 +2026-01-04 18:28:49.735990 eGaQ1L5pcF8 from=from@from queued ip=127.0.0.1:53286 to=[to@localhost] +_ queue.go:308 Queue.SendLoop eGaQ1L5pcF8: from from@from +_ queue.go:367 Queue.SendLoop eGaQ1L5pcF8: testuser@localhost sent +2026-01-04 18:28:49.736132 eGaQ1L5pcF8 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop eGaQ1L5pcF8: all done +2026-01-04 18:28:49.751591 eGaQ1L5pcF8 from=from@from all done +_ conn.go:318 SMTP.Conn 127.0.0.1:53286: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:722 SMTP.Conn 127.0.0.1:53286: Queued from from@from to [to@localhost] - O58Dke8TgYI +2026-01-04 18:28:50.163585 O58Dke8TgYI from=from@from queued ip=127.0.0.1:53286 to=[to@localhost] +_ queue.go:308 Queue.SendLoop O58Dke8TgYI: from from@from +_ queue.go:367 Queue.SendLoop O58Dke8TgYI: testuser@localhost sent +2026-01-04 18:28:50.163781 O58Dke8TgYI from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.89s) === RUN TestSimpleCommands -_ conn.go:318 SMTP.Conn 127.0.0.1:45998: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. -_ conn.go:318 SMTP.Conn 127.0.0.1:45998: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:318 SMTP.Conn 127.0.0.1:53292: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. +_ conn.go:318 SMTP.Conn 127.0.0.1:53292: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. --- PASS: TestSimpleCommands (0.00s) === RUN TestLongLines -_ conn.go:318 SMTP.Conn 127.0.0.1:46000: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:348 SMTP.Conn 127.0.0.1:46000: error: exiting with error: line too long +_ conn.go:318 SMTP.Conn 127.0.0.1:53304: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:348 SMTP.Conn 127.0.0.1:53304: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.00s) === RUN TestRepeatedStartTLS -_ conn.go:318 SMTP.Conn 127.0.0.1:46024: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestRepeatedStartTLS (0.01s) +_ queue.go:342 Queue.SendLoop O58Dke8TgYI: all done +2026-01-04 18:28:50.175178 O58Dke8TgYI from=from@from all done +_ conn.go:318 SMTP.Conn 127.0.0.1:53326: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestRepeatedStartTLS (0.02s) === RUN TestStartTLSOnTLS -_ queue.go:342 Queue.SendLoop jPwc-9xBRCA: all done -2024-12-02 12:04:04.098766 jPwc-9xBRCA from=from@from all done -_ conn.go:318 SMTP.Conn 127.0.0.1:46662: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:318 SMTP.Conn 127.0.0.1:39662: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestStartTLSOnTLS (0.01s) === RUN TestAddDKIMSigner - testlib.go:32: test directory: "/tmp/testlib_1077879956" + testlib.go:32: test directory: "/tmp/testlib_4081501524" --- PASS: TestAddDKIMSigner (0.01s) === RUN FuzzConnection --- PASS: FuzzConnection (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.683s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.024s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -2339,31 +2373,31 @@ --- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.01s) +--- PASS: TestPolicyTooBig (0.03s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_3318276263" + testlib.go:32: test directory: "/tmp/testlib_3677607160" sts_test.go:277: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:286: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:300: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:309: cache fetched domErr: --- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_3792707284" + testlib.go:32: test directory: "/tmp/testlib_4277606432" 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.00s) +--- PASS: TestCacheBadData (0.01s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_35329936" + testlib.go:32: test directory: "/tmp/testlib_1760922622" sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 1m40s} sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 1m40s} sts_test.go:389: Fetch "refresh-test": &{STSv1 enforce [mx] 3m20s} --- PASS: TestCacheRefresh (0.01s) === RUN TestCacheSlashSafe - testlib.go:32: test directory: "/tmp/testlib_4058039528" + testlib.go:32: test directory: "/tmp/testlib_506433797" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -2373,9 +2407,9 @@ === RUN TestHTTPGet --- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.038s +ok blitiri.com.ar/go/chasquid/internal/sts 0.066s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_41757553" + testlib.go:32: test directory: "/tmp/testlib_2255460223" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -2386,54 +2420,54 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_2478919100" + testlib.go:32: test directory: "/tmp/testlib_3816934055" --- PASS: TestRewrite (0.00s) === RUN TestGetFreePort --- PASS: TestGetFreePort (0.00s) === RUN TestWaitFor ---- PASS: TestWaitFor (0.02s) +--- PASS: TestWaitFor (0.03s) === RUN TestGenerateCert - testlib.go:32: test directory: "/tmp/testlib_994649009" ---- PASS: TestGenerateCert (0.05s) + testlib.go:32: test directory: "/tmp/testlib_2897760382" +--- PASS: TestGenerateCert (0.15s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.08s) +--- PASS: TestGenerateCertBadDir (0.06s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.155s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.244s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.003s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.006s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test2148906812" - userdb_test.go:37: file: "/tmp/userdb_test723047378" + userdb_test.go:37: file: "/tmp/userdb_test20042893" + userdb_test.go:37: file: "/tmp/userdb_test2126529005" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test2705140057" ---- PASS: TestWrite (0.45s) + userdb_test.go:37: file: "/tmp/userdb_test2485494413" +--- PASS: TestWrite (0.53s) === RUN TestNew --- PASS: TestNew (0.06s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test4125263831" + userdb_test.go:37: file: "/tmp/userdb_test2687396598" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test100146122" + userdb_test.go:37: file: "/tmp/userdb_test1311405522" --- PASS: TestPlainScheme (0.00s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test215347568" + userdb_test.go:37: file: "/tmp/userdb_test2005743980" --- PASS: TestDeniedScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test1028963093" + userdb_test.go:37: file: "/tmp/userdb_test1714974314" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test1821634228" ---- PASS: TestRemoveUser (0.08s) + userdb_test.go:37: file: "/tmp/userdb_test2728071282" +--- PASS: TestRemoveUser (0.07s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test2312946657" ---- PASS: TestExists (0.06s) + userdb_test.go:37: file: "/tmp/userdb_test398008921" +--- PASS: TestExists (0.07s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 0.654s +ok blitiri.com.ar/go/chasquid/internal/userdb 0.763s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -2480,8 +2514,8 @@ dh_strip -a -O--buildsystem=golang dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/mda-lmtp dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/smtp-check -dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid-util +dh_strip: warning: Could not find the BuildID in debian/chasquid/usr/bin/chasquid dh_makeshlibs -a -O--buildsystem=golang dh_shlibdeps -a -O--buildsystem=golang dh_installdeb -O--buildsystem=golang @@ -2498,12 +2532,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/29472/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/29472/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/6681 and its subdirectories -I: Current time: Mon Dec 2 00:04:20 -12 2024 -I: pbuilder-time-stamp: 1733141060 +I: removing directory /srv/workspace/pbuilder/29472 and its subdirectories +I: Current time: Mon Jan 5 08:29:08 +14 2026 +I: pbuilder-time-stamp: 1767551348