Diff of the two buildlogs: -- --- b1/build.log 2024-06-03 00:10:29.605624368 +0000 +++ b2/build.log 2024-06-03 00:13:47.673208468 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sat Jul 5 18:32:13 -12 2025 -I: pbuilder-time-stamp: 1751783533 +I: Current time: Mon Jun 3 14:10:32 +14 2024 +I: pbuilder-time-stamp: 1717373432 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -28,54 +28,86 @@ 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/34942/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/16646/tmp/hooks/D01_modify_environment starting +debug: Running on ionos2-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 Jun 3 00:10 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/16646/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/16646/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=22 ' - DISTRIBUTION='unstable' - 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]="21" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.2.21(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=10 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='24123860423a47d6b660079711e1e782' - 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='34942' - PS1='# ' - PS2='> ' + INVOCATION_ID=9306506a5fc44beba9aa53c9901dc456 + 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=16646 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.3guRfjUP/pbuilderrc_8Mqm --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3guRfjUP/b1 --logfile b1/build.log chasquid_1.14.0-2.dsc' - SUDO_GID='112' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://213.165.73.152:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.3guRfjUP/pbuilderrc_leR3 --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3guRfjUP/b2 --logfile b2/build.log chasquid_1.14.0-2.dsc' + SUDO_GID=112 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://46.16.76.132:3128 I: uname -a - Linux ionos6-i386 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jul 4 14:05 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/34942/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 2 07:42 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/16646/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -178,7 +210,7 @@ Get: 43 http://deb.debian.org/debian unstable/main i386 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 44 http://deb.debian.org/debian unstable/main i386 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 45 http://deb.debian.org/debian unstable/main i386 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 68.8 MB in 1s (73.0 MB/s) +Fetched 68.8 MB in 1s (47.7 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 ... 19704 files and directories currently installed.) @@ -373,7 +405,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/16646/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/16646/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 @@ -393,245 +429,245 @@ 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 22 -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 -cmp + cd obj-i686-linux-gnu && go install -trimpath -v -p 10 -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/unsafeheader internal/goarch -unicode/utf16 -internal/goos +internal/godebugs internal/coverage/rtcov -internal/nettrace +internal/goexperiment +internal/goos +internal/itoa +internal/cpu math/bits runtime/internal/atomic -log/internal -internal/godebugs +runtime/internal/syscall +internal/chacha8rand +internal/abi +runtime/internal/math +runtime/internal/sys unicode/utf8 -sync/atomic -encoding -internal/unsafeheader -internal/cpu internal/race -runtime/internal/syscall -container/list +sync/atomic +math unicode +cmp +log/internal +internal/bytealg +internal/nettrace google.golang.org/protobuf/internal/flags -internal/itoa +slices +unicode/utf16 +google.golang.org/protobuf/internal/set +container/ring +container/list crypto/internal/alias crypto/internal/boring/sig -internal/goexperiment vendor/golang.org/x/crypto/cryptobyte/asn1 vendor/golang.org/x/crypto/internal/alias blitiri.com.ar/go/chasquid/internal/set -container/ring -runtime/internal/math -internal/chacha8rand -internal/abi -runtime/internal/sys -google.golang.org/protobuf/internal/set -slices -math -internal/bytealg runtime -crypto/subtle internal/reflectlite +crypto/subtle sync internal/testlog +internal/bisect internal/singleflight google.golang.org/protobuf/internal/pragma -internal/bisect runtime/cgo errors sort -internal/godebug -strconv -golang.org/x/text/internal/tag +io +internal/oserror path internal/safefilepath -internal/intern -internal/oserror -io -math/rand vendor/golang.org/x/net/dns/dnsmessage +strconv crypto/internal/nistec/fiat syscall -hash -crypto/internal/randutil -text/tabwriter +internal/godebug strings +reflect +crypto/internal/randutil +hash bytes +math/rand +internal/intern +net/netip hash/fnv hash/crc32 -crypto/rc4 +internal/syscall/unix +time +internal/syscall/execenv crypto -net/netip -reflect -golang.org/x/text/transform -vendor/golang.org/x/text/transform -bufio html -net/http/internal/ascii regexp/syntax +crypto/rc4 +net/http/internal/ascii +golang.org/x/text/internal/tag +text/tabwriter +bufio +vendor/golang.org/x/text/transform +golang.org/x/text/transform golang.org/x/text/runes golang.org/x/text/width regexp -internal/syscall/execenv -internal/syscall/unix -time -internal/fmtsort -encoding/binary -crypto/internal/nistec -io/fs internal/poll +io/fs context +crypto/internal/nistec embed +google.golang.org/protobuf/internal/editiondefaults +os +internal/fmtsort +encoding/binary encoding/base64 -vendor/golang.org/x/crypto/internal/poly1305 crypto/cipher crypto/internal/edwards25519/field crypto/md5 +vendor/golang.org/x/crypto/internal/poly1305 golang.org/x/sys/unix -google.golang.org/protobuf/internal/editiondefaults +crypto/internal/edwards25519 encoding/pem -os vendor/golang.org/x/crypto/chacha20 crypto/internal/boring crypto/des -crypto/internal/edwards25519 -crypto/hmac +google.golang.org/protobuf/internal/detrand +path/filepath +fmt +io/ioutil +net +blitiri.com.ar/go/chasquid/internal/safeio +crypto/aes +crypto/ecdh crypto/sha512 +crypto/hmac crypto/sha1 -crypto/ecdh crypto/sha256 -crypto/aes vendor/golang.org/x/crypto/chacha20poly1305 vendor/golang.org/x/crypto/hkdf +os/exec golang.org/x/crypto/pbkdf2 -golang.org/x/crypto/scrypt -google.golang.org/protobuf/internal/detrand internal/lazyregexp -blitiri.com.ar/go/chasquid/internal/safeio -internal/sysinfo -io/ioutil -fmt -path/filepath os/signal -net -os/exec -google.golang.org/protobuf/internal/errors -log +internal/sysinfo +golang.org/x/crypto/scrypt flag +compress/flate +log +google.golang.org/protobuf/internal/errors go/token google.golang.org/protobuf/internal/version -encoding/hex -blitiri.com.ar/go/chasquid/internal/envelope -net/http/internal -mime/quotedprintable net/url -text/template/parse -golang.org/x/text/unicode/norm -mime encoding/json -math/big -compress/flate -vendor/golang.org/x/net/http2/hpack -golang.org/x/text/internal/language -vendor/golang.org/x/text/unicode/norm -blitiri.com.ar/go/chasquid/internal/tlsconst +text/template/parse google.golang.org/protobuf/encoding/protowire -runtime/trace -runtime/debug +math/big google.golang.org/protobuf/reflect/protoreflect +encoding/hex vendor/golang.org/x/text/unicode/bidi -golang.org/x/text/unicode/bidi -testing +vendor/golang.org/x/text/unicode/norm +vendor/golang.org/x/net/http2/hpack compress/gzip -golang.org/x/text/internal/language/compact +mime +mime/quotedprintable +net/http/internal google.golang.org/protobuf/internal/encoding/messageset -text/template google.golang.org/protobuf/internal/strs +google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order google.golang.org/protobuf/runtime/protoiface -google.golang.org/protobuf/internal/descopts -google.golang.org/protobuf/internal/descfmt -internal/profile -google.golang.org/protobuf/internal/genid -runtime/pprof google.golang.org/protobuf/reflect/protoregistry -golang.org/x/term +google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/encoding/text -golang.org/x/text/language -golang.org/x/text/secure/bidirule +google.golang.org/protobuf/internal/descopts +text/template vendor/golang.org/x/text/secure/bidirule -golang.org/x/net/idna +blitiri.com.ar/go/chasquid/internal/envelope +golang.org/x/text/unicode/bidi +golang.org/x/text/unicode/norm vendor/golang.org/x/net/idna +golang.org/x/text/internal/language google.golang.org/protobuf/proto -golang.org/x/text/internal google.golang.org/protobuf/internal/encoding/defval -html/template -golang.org/x/text/cases +blitiri.com.ar/go/chasquid/internal/tlsconst crypto/rand -crypto/internal/boring/bbig -crypto/dsa -crypto/internal/bigmod crypto/elliptic -encoding/asn1 -crypto/ed25519 google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc +crypto/internal/bigmod +crypto/internal/boring/bbig +encoding/asn1 +crypto/ed25519 +html/template +crypto/dsa +golang.org/x/text/secure/bidirule +golang.org/x/text/internal/language/compact +internal/profile crypto/rsa -crypto/x509/pkix -vendor/golang.org/x/crypto/cryptobyte +golang.org/x/net/idna blitiri.com.ar/go/chasquid/internal/protoio -golang.org/x/text/secure/precis +runtime/pprof +runtime/trace +golang.org/x/text/language +runtime/debug +golang.org/x/term +testing +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +golang.org/x/text/internal +golang.org/x/text/cases crypto/ecdsa google.golang.org/protobuf/internal/encoding/tag -blitiri.com.ar/go/chasquid/internal/normalize +golang.org/x/text/secure/precis google.golang.org/protobuf/internal/impl -blitiri.com.ar/go/chasquid/internal/haproxy +blitiri.com.ar/go/chasquid/internal/normalize log/syslog -blitiri.com.ar/go/systemd -vendor/golang.org/x/net/http/httpproxy net/textproto -blitiri.com.ar/go/spf +vendor/golang.org/x/net/http/httpproxy crypto/x509 +blitiri.com.ar/go/chasquid/internal/haproxy +blitiri.com.ar/go/spf +blitiri.com.ar/go/systemd blitiri.com.ar/go/log -blitiri.com.ar/go/chasquid/cmd/mda-lmtp vendor/golang.org/x/net/http/httpguts blitiri.com.ar/go/chasquid/internal/dovecot -net/mail mime/multipart +net/mail +blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/dkim -google.golang.org/protobuf/internal/filetype crypto/tls +google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl +blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/protoio/testpb blitiri.com.ar/go/chasquid/internal/userdb -blitiri.com.ar/go/chasquid/internal/config +net/http/httptrace net/smtp blitiri.com.ar/go/chasquid/internal/testlib -net/http/httptrace net/http blitiri.com.ar/go/chasquid/internal/smtp -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/localrpc blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/aliases +blitiri.com.ar/go/chasquid/internal/auth +blitiri.com.ar/go/chasquid/internal/localrpc blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/cmd/chasquid-util -blitiri.com.ar/go/chasquid/cmd/smtp-check 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 22 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 10 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] @@ -665,7 +701,7 @@ _ aliases.go:547 Hook.Alias-Resolve a@localA: error: exit status 1 _ aliases.go:547 Hook.Alias-Resolve *@localA: error: exit status 1 _ aliases.go:547 Hook.Alias-Resolve a@localA: error: exit status 1 ---- PASS: TestHookError (0.00s) +--- PASS: TestHookError (0.07s) === RUN FuzzReader === RUN FuzzReader/seed#0 === RUN FuzzReader/1c24d2215db69748c6fd16797673ad11ebc7e6167fe1bc1f54c6959ec10407b6 @@ -673,7 +709,7 @@ === RUN FuzzReader/8234d8c5719f30e50525290db70743bf97d940e60591cf4a638c72158d35504a === RUN FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e === RUN FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d ---- PASS: FuzzReader (0.00s) +--- PASS: FuzzReader (0.02s) --- PASS: FuzzReader/seed#0 (0.00s) --- PASS: FuzzReader/1c24d2215db69748c6fd16797673ad11ebc7e6167fe1bc1f54c6959ec10407b6 (0.00s) --- PASS: FuzzReader/4adaceaa32e2b32c00322948769d62c2dd42e1d9f4950d3c5b411c710e6d4a86 (0.00s) @@ -681,7 +717,7 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.018s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.144s === 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 @@ -694,7 +730,7 @@ 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.44s) +--- PASS: TestAuthenticate (0.64s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends @@ -715,7 +751,7 @@ === RUN FuzzDecodeResponse/c2ae184876dd0fe9acfc8a5e2f2174a968b889b01e0f5c9a61fa27d7361f0091 === RUN FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 === RUN FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 ---- PASS: FuzzDecodeResponse (0.00s) +--- PASS: FuzzDecodeResponse (0.01s) --- PASS: FuzzDecodeResponse/0274b170c6fe2654ca5418a914b804e9c7cc5d8e5c2a7c5fcf5c29540ec5ae52 (0.00s) --- PASS: FuzzDecodeResponse/24d35771ef1fe0645d90b061e13a777faea328736483ec2833b63950d26b5399 (0.00s) --- PASS: FuzzDecodeResponse/2e5d0b26626f2d2dd6fb423e1e1cc432277ae9877c622fe6ca067e247bc11c9d (0.00s) @@ -727,47 +763,46 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.642s +ok blitiri.com.ar/go/chasquid/internal/auth 0.859s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_1807591911" + testlib.go:32: test directory: "/tmp/testlib_4263322143" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_3418973751" + testlib.go:32: test directory: "/tmp/testlib_2030480697" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_3784234661" + testlib.go:32: test directory: "/tmp/testlib_3730932679" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_3114162441" + testlib.go:32: test directory: "/tmp/testlib_874217170" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.007s +ok blitiri.com.ar/go/chasquid/internal/config 0.051s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] -? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_1329023347" ---- PASS: TestMDA (0.00s) + testlib.go:32: test directory: "/tmp/testlib_1199972515" +--- PASS: TestMDA (0.02s) === 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_964931790" - testlib.go:32: test directory: "/tmp/testlib_1863000811" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::45419: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::45419: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_1016157900" + testlib.go:32: test directory: "/tmp/testlib_2977860547" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::37019: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::37019: 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:" @@ -775,26 +810,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.07s) +--- PASS: TestSMTP (0.18s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_4147161524" - testlib.go:32: test directory: "/tmp/testlib_506639786" + testlib.go:32: test directory: "/tmp/testlib_1576231702" + testlib.go:32: test directory: "/tmp/testlib_3219503043" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:52436->127.0.0.1:38345: 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:52436->127.0.0.1:38345: 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:52436->127.0.0.1:38345: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:44986->127.0.0.1:39725: 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:44986->127.0.0.1:39725: 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:44986->127.0.0.1:39725: 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:44986->127.0.0.1:39725: i/o timeout) fakeserver_test.go:105: fakeServer exiting: EOF - smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:52436->127.0.0.1:38345: i/o timeout) - testlib.go:32: test directory: "/tmp/testlib_4034753957" - testlib.go:32: test directory: "/tmp/testlib_40039651" + testlib.go:32: test directory: "/tmp/testlib_3585836067" + testlib.go:32: test directory: "/tmp/testlib_3994213397" 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_2346890515" - testlib.go:32: test directory: "/tmp/testlib_3683540673" + testlib.go:32: test directory: "/tmp/testlib_2261450221" + testlib.go:32: test directory: "/tmp/testlib_293693336" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -802,8 +837,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_1548346822" - testlib.go:32: test directory: "/tmp/testlib_3537731084" + testlib.go:32: test directory: "/tmp/testlib_3680113514" + testlib.go:32: test directory: "/tmp/testlib_2796856253" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -812,8 +847,8 @@ _ smtp.go:207 Courier.SMTP to@to: error: DATA 554 data error smtp_test.go:140: failed as expected: DATA 554 data error fakeserver_test.go:132: fakeServer exiting: unexpected EOF - testlib.go:32: test directory: "/tmp/testlib_4268985353" - testlib.go:32: test directory: "/tmp/testlib_1544571588" + testlib.go:32: test directory: "/tmp/testlib_1525746135" + testlib.go:32: test directory: "/tmp/testlib_3374827464" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -822,9 +857,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.43s) +--- PASS: TestSMTPErrors (3.51s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_2027497688" + testlib.go:32: test directory: "/tmp/testlib_2581217610" _ 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) @@ -839,8 +874,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_305397938" - testlib.go:32: test directory: "/tmp/testlib_89312225" + testlib.go:32: test directory: "/tmp/testlib_3314442584" + testlib.go:32: test directory: "/tmp/testlib_1475641533" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -851,34 +886,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_69375775" + testlib.go:32: test directory: "/tmp/testlib_2657202351" 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) _ smtp.go:101 Courier.SMTP to@to: error: \"localhost\" returned transient error: Security level check failed (level:PLAIN) - fakeserver_test.go:105: fakeServer exiting: EOF _ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: Security level check failed (level:PLAIN)) ---- PASS: TestTLS (0.20s) + fakeserver_test.go:105: fakeServer exiting: EOF +--- PASS: TestTLS (0.52s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_3620739378" - testlib.go:32: test directory: "/tmp/testlib_2043088557" + testlib.go:32: test directory: "/tmp/testlib_1516988469" + testlib.go:32: test directory: "/tmp/testlib_1591732170" 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:105: fakeServer exiting: EOF fakeserver_test.go:98: fakeServer got connection + fakeserver_test.go:105: fakeServer exiting: EOF 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.14s) +--- PASS: TestTLSError (0.23s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_440247096" - testlib.go:32: test directory: "/tmp/testlib_662757858" + testlib.go:32: test directory: "/tmp/testlib_352876296" + testlib.go:32: test directory: "/tmp/testlib_4043202035" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -886,7 +921,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_1885203277" + testlib.go:32: test directory: "/tmp/testlib_3185950311" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -897,9 +932,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.16s) +--- PASS: TestSTSPolicyEnforcement (0.65s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.119s +ok blitiri.com.ar/go/chasquid/internal/courier 5.245s === RUN TestStringToCanonicalization --- PASS: TestStringToCanonicalization (0.00s) === RUN TestSimpleBody @@ -935,7 +970,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:[0xaa1c0f0 0xaa1c120]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa5ea150 0xa5ea180]} 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; @@ -979,7 +1014,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:[0xaa1c420 0xaa1c450 0xaa1c480 0xaa1c4b0 0xaa1c4e0]} + file_test.go:56: Want result: &{Found:5 Valid:5 Results:[0xa5ea480 0xa5ea4b0 0xa5ea4e0 0xa5ea510 0xa5ea540]} 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; @@ -1091,7 +1126,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:[0xaa1c930]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xa5ea990]} 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; @@ -1105,7 +1140,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:[0xaa1ca80 0xaa1cab0]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xa5eaae0 0xa5eab10]} 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; @@ -1139,7 +1174,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:[0xaa1ccf0 0xaa1cd20]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xa5ead50 0xa5ead80]} 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; @@ -1173,7 +1208,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:[0xaa1cf30 0xaa1cf60]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa5eaf90 0xa5eafc0]} 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; @@ -1220,7 +1255,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:[0xaa1d260 0xaa1d290 0xaa1d2c0]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xa5eb2c0 0xa5eb2f0 0xa5eb320]} 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; @@ -1280,7 +1315,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:[0xaa1d650 0xaa1d680 0xaa1d6b0]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xa5eb6b0 0xa5eb6e0 0xa5eb710]} 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; @@ -1340,7 +1375,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:[0xaa1da10 0xaa1da40]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xa5eba70 0xa5ebaa0]} 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; @@ -1386,7 +1421,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:[0xaa1dcb0]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xa5ebd10]} 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; @@ -1407,17 +1442,17 @@ 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.04s) - --- PASS: TestFromFiles/testdata/01-rfc8463 (0.01s) +--- PASS: TestFromFiles (0.11s) + --- PASS: TestFromFiles/testdata/01-rfc8463 (0.02s) --- PASS: TestFromFiles/testdata/02-too_many_headers (0.01s) --- PASS: TestFromFiles/testdata/03-bad_message (0.00s) --- PASS: TestFromFiles/testdata/04-bad_dkim_signature_header (0.00s) --- PASS: TestFromFiles/testdata/05-dns_temp_error (0.00s) --- 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/10-strict_domain_check_pass (0.00s) + --- PASS: TestFromFiles/testdata/07-algo_mismatch (0.01s) + --- PASS: TestFromFiles/testdata/08-our_signature (0.03s) + --- PASS: TestFromFiles/testdata/09-limited_body (0.01s) + --- PASS: TestFromFiles/testdata/10-strict_domain_check_pass (0.01s) --- PASS: TestFromFiles/testdata/11-strict_domain_check_fail (0.00s) === RUN TestFromPrivateFiles --- PASS: TestFromPrivateFiles (0.00s) @@ -1442,18 +1477,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=1751783589; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "ZvgIQQjBQ7GHXsgCbedBYQySdyzhn+X8OVlpKoioyrE=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "gs/mSFc616qCjq6N+pX+fs5U/7P2jHfFDyH2ZxM2o1I=" context.go:17: Found DKIM-Signature header: v=1; a=rsa-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1751783589; + d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=BdARIyUq+3tdzwZo/8hy991KK57xSZb8UCy+wGpFF7NdkM303tK+mLx5xPdP3yzrC7lWZk - TgzrFPAM20qmYv3b4hEzuNS9GXHSQbq9+4+6rpf6EcWaIhByMcB1eeJv+JtRP4RoYH26zN - jp0CJZUXItmPZFmLmihsqVuVCB5+Y/Fhj9TQ/dCKoGxT3P+6EuiF9EVyTEPKv1RLSo896N - IrPHmE0A/7vdKjDjxZb8VQqBiIo/Aom1U0rP0g/rTR5nJIKXyYet9WWN4GdAY+Ao6HcdFm - C7qy0L1Q8l/vVDbFq0GBQOmy+izu73vnwDrGUJlVCR637z4fajKys8olN82Czg==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA6C/G62mGTjsN7+NZ/+sDnZ3BiaYe1wViIMdIEqML1gjqEqhfUYhf20oPio1P3dNi/5rcl92PV0sMnaRXE6Er9Z0CFXU0U+H23vi+g4zxOxA3MOrfa952kBjgkYfF21s72fohYptUZqDRF4iJUyacNJkixbZwLMwsSDl3AFcG3XN9UdGehxETjnW+WAhOQnKLv8z/J2h+lRcznfBQickJMcTmAoOFl7ly4UsnO95JWyLQtbNnNYgdrSlwmVSh4K4FMQqNFty+jlBnx/eW0+UBw03OQm4yqLOaTF/w34Cc1X8tMzBAJJZSbdIyLpq8Q8R0udThixF6v4vJ3xbHUK6WpwIDAQAB" + b=of8meSfUd4xNDySuGLaV8ln1Q2DLW5ksOrZZUN4F6qic3eot4MyQsECnzbqVnam7xgVkyA + 6mevHkKZoIU2723B0o+G2QuN9m48xHhxDPODcWX8ASYBY4bSCVnAeJ/dOtTxEbZaDBop9o + fE4QFR2OSXGFF04kMPZ8gR73vrLR+cU9ivXL1gCZNXArv5sx/zXV2elYsIrWWBBiqLV5fp + UJJzFPWS1f+WTRxtljMenkIAmtjd0hf0aUmeK9TZlHGzd4tHx3zVGDd+F57qoPHV4qF5RC + Lt1GKa+30tl/sTHPQ8uulA2H36akoXYPZlQbDL63fhZbfkbEWA7DZ7pS9wYPnA==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAs6Oja8P+LmgeSIh0bYf9QLYfJgIrfOeVWFA1k5UufDRjGKFCceKQIazhG6wGgvhNUcxplQ3TirQTDuvPH2jdbFYze0aCfWzQOCxRP0bABM90XuS7RRab0k1JDTf7O+7MJ5vkc+yQOGyqoxUvyimH6Zh2Ee/xFWMcUxAn9fydOKFc4kDRNeFe3zSw6S1YVk1fducIN8HV7KWK+9lMA1aBGTXEPe6vv6hJRZj3evyIg7GmHYYG28AVYD54JUyE6CCRzh+kkxeKt+D+V2pf6/Vh1t9xohvyl3QIm20AU1v5lwfFYBjijuk25ZLtSeU6LWxf+VL73BI4BNQcYpLQI74VKQIDAQAB" 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" @@ -1461,11 +1496,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=1751783589; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "ZvgIQQjBQ7GHXsgCbedBYQySdyzhn+X8OVlpKoioyrE=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "gs/mSFc616qCjq6N+pX+fs5U/7P2jHfFDyH2ZxM2o1I=" context.go:17: PK [rsa:30820122300d0609]: Verification succeeded context.go:17: Found 1 signatures, 1 valid ---- PASS: TestSignRSA (1.61s) +--- PASS: TestSignRSA (4.65s) === RUN TestSignEd25519 context.go:17: Signing for example.com / test with ed25519-sha256 context.go:17: Hashing header: "from:Joe SixPack \r\n" @@ -1473,27 +1508,27 @@ 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=1751783589; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "Ce2q38ecSnFzfNUzmP03rBDFqKnsMhTv1b/NpSoHGvs=" + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "IQvIORDTwYoF8LiGU67eDTqktWiU071Pc+qF+bnSKfw=" context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1751783589; + d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=CjoXLSC92G5Uun31INy9sQPHDSV/P5yxAWlpnM/LBJGUe3UdDA+vYwfpDRVr4zd0AWbCd/ - eUSesdpmeTztr8CA==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=S8nP67NUX6y3fa6vAZ8owLydvUN4gGHdZYUXJWdV8Ic=" - context.go:17: Parsed public key: [ed25519:4bc9cfebb3545fac] + b=4aUzRe7p7SYxXdQxSdx1F4pd4EhUoD/cbcij6ZaIYJaCudNDHOFDVqVckrCXE8EE9mgqqp + ng5joANytqop9MAQ==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=ctuvCJop/VEeVMS9f/dSMWokqLdoPyOHxjOzoPbKD5o=" + context.go:17: Parsed public key: [ed25519:72dbaf089a29fd51] 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=1751783589; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "Ce2q38ecSnFzfNUzmP03rBDFqKnsMhTv1b/NpSoHGvs=" - context.go:17: PK [ed25519:4bc9cfebb3545fac]: Verification succeeded + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1717373579; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "IQvIORDTwYoF8LiGU67eDTqktWiU071Pc+qF+bnSKfw=" + context.go:17: PK [ed25519:72dbaf089a29fd51]: Verification succeeded context.go:17: Found 1 signatures, 1 valid ---- PASS: TestSignEd25519 (0.02s) +--- PASS: TestSignEd25519 (0.04s) === RUN TestSignBadMessage --- PASS: TestSignBadMessage (0.00s) === RUN TestSignBadAlgorithm @@ -1654,7 +1689,7 @@ context.go:17: Resulting hash: "f2IsMaznsSl75RyN3HCeXBCknzhGOAtiH+WoXk/PiLI=" context.go:17: PK [rsa:30819f300d06092a]: Verification failed: crypto/rsa: verification error context.go:17: Found 2 signatures, 0 valid ---- PASS: TestVerifyRFC8463Example (0.00s) +--- PASS: TestVerifyRFC8463Example (0.01s) === RUN TestHeadersToInclude --- PASS: TestHeadersToInclude (0.00s) === RUN TestAuthenticationResults @@ -1690,25 +1725,25 @@ --- PASS: FuzzParsePublicKey/seed#12 (0.00s) --- PASS: FuzzParsePublicKey/seed#13 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dkim 1.703s +ok blitiri.com.ar/go/chasquid/internal/dkim 4.868s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1083052958" + testlib.go:32: test directory: "/tmp/testlib_651569563" _ 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.10s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_185264697" + testlib.go:32: test directory: "/tmp/testlib_4204295740" _ 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) +--- PASS: TestNewDomain (0.01s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_1971970554" + testlib.go:32: test directory: "/tmp/testlib_125178004" _ 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 @@ -1723,27 +1758,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_1890007717" + testlib.go:32: test directory: "/tmp/testlib_2546232242" _ 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_565104123" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_565104123/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_565104123/db/.s:d12226083277: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_613375591" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_613375591/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_613375591/db/.s:d12464493991: no such file or directory --- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.012s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.135s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_2958613756" + testlib.go:32: test directory: "/tmp/testlib_3489106152" --- PASS: TestAutodetect (0.00s) === 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.047s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -1751,13 +1786,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.020s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.006s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.057s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -1782,21 +1817,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.048s === RUN TestBadServer - asm_386.s:1363: FakeServer &{{0xa282000}}: accepted - asm_386.s:1363: FakeServer &{{0xa282000}}: readRequest: "" "" / EOF - asm_386.s:1363: FakeServer &{{0xa282000}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-3819336546/rpc.sock->@: write: broken pipe - asm_386.s:1363: FakeServer &{{0xa282000}}: closing - asm_386.s:1363: FakeServer &{{0xa2820a0}}: accepted - asm_386.s:1363: FakeServer &{{0xa2820a0}}: readRequest: "Echo" "" / - asm_386.s:1363: FakeServer &{{0xa2820a0}}: writeMessage("xxx"): 3 - asm_386.s:1363: FakeServer &{{0xa2820a0}}: closing + asm_386.s:1363: FakeServer &{{0x98a60f0}}: accepted + asm_386.s:1363: FakeServer &{{0x98a60f0}}: readRequest: "" "" / EOF + asm_386.s:1363: FakeServer &{{0x98a60f0}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-509306488/rpc.sock->@: write: broken pipe + asm_386.s:1363: FakeServer &{{0x98a60f0}}: closing + asm_386.s:1363: FakeServer &{{0x98a6190}}: accepted + asm_386.s:1363: FakeServer &{{0x98a6190}}: readRequest: "Echo" "" / + asm_386.s:1363: FakeServer &{{0x98a6190}}: writeMessage("xxx"): 3 + asm_386.s:1363: FakeServer &{{0x98a6190}}: closing --- PASS: TestBadServer (0.01s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-2948468012/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-2950595389/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1804,9 +1839,9 @@ _ 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-2948468012/sock: error: Accept error: accept unix /tmp/rpc-test-2948468012/sock: use of closed network connection ---- PASS: TestEndToEnd (0.01s) - --- PASS: TestEndToEnd/Echo (0.00s) +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-2950595389/sock: error: Accept error: accept unix /tmp/rpc-test-2950595389/sock: use of closed network connection +--- PASS: TestEndToEnd (0.02s) + --- PASS: TestEndToEnd/Echo (0.01s) --- PASS: TestEndToEnd/Echo#01 (0.00s) --- PASS: TestEndToEnd/Hola (0.00s) --- PASS: TestEndToEnd/HolaErr (0.00s) @@ -1819,7 +1854,7 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.031s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.090s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -1827,7 +1862,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.053s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1835,15 +1870,15 @@ === 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) +--- PASS: TestHTTPErrors (0.02s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.02s) +--- PASS: TestHTTPUroboro (0.03s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.00s) +--- PASS: TestHTTPDeep (0.02s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler @@ -1863,7 +1898,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.058s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.127s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1880,7 +1915,7 @@ === RUN FuzzUser/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 === RUN FuzzUser/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzUser/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzUser (0.00s) +--- PASS: FuzzUser (0.03s) --- PASS: FuzzUser/263da65bb5a59369f294d26a64a36a989a9a36ed5c60950b123e395bedbe881c (0.00s) --- PASS: FuzzUser/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzUser/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 (0.00s) @@ -1892,7 +1927,7 @@ === RUN FuzzDomain/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 === RUN FuzzDomain/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzDomain/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzDomain (0.00s) +--- PASS: FuzzDomain (0.02s) --- PASS: FuzzDomain/263da65bb5a59369f294d26a64a36a989a9a36ed5c60950b123e395bedbe881c (0.00s) --- PASS: FuzzDomain/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzDomain/6d603c8b9fbe8b9aa021dbde499ec1b3a00922b9338c68b2984cd314c3d5e633 (0.00s) @@ -1904,7 +1939,7 @@ === RUN FuzzAddr/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzAddr/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 === RUN FuzzAddr/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzAddr (0.00s) +--- PASS: FuzzAddr (0.01s) --- PASS: FuzzAddr/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzAddr/7aba1e0ef80990ccac3731800dbb0267c4c8b7156d4da3b8a5f1b57a570adfb8 (0.00s) --- PASS: FuzzAddr/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 (0.00s) @@ -1916,47 +1951,47 @@ === RUN FuzzDomainToUnicode/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 === RUN FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 === RUN FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 ---- PASS: FuzzDomainToUnicode (0.00s) +--- PASS: FuzzDomainToUnicode (0.01s) --- PASS: FuzzDomainToUnicode/31400a53be6363c91bf6585789663189fa30b16181c1d18f19708acccc85f4a1 (0.00s) --- PASS: FuzzDomainToUnicode/7aba1e0ef80990ccac3731800dbb0267c4c8b7156d4da3b8a5f1b57a570adfb8 (0.00s) --- PASS: FuzzDomainToUnicode/ccde73fe7b7352806a87cece8eb81867bdeb177019b69a4bb3c7bb5a277b9c32 (0.00s) --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.011s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.100s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_903685645" + testlib.go:32: test directory: "/tmp/testlib_537496310" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_4219452355" + testlib.go:32: test directory: "/tmp/testlib_2803278361" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_2988299846" ---- PASS: TestStore (0.00s) + testlib.go:32: test directory: "/tmp/testlib_2371881052" +--- PASS: TestStore (0.01s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_1318398787" + testlib.go:32: test directory: "/tmp/testlib_1742556174" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_1470351040" + testlib.go:32: test directory: "/tmp/testlib_1056606012" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.006s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.029s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Sun, 06 Jul 2025 06:33:07 +0000 + Message-ID: + Date: Mon, 03 Jun 2024 00:12:59 +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="2I6UYICDbak" + boundary="qLE9ygFP5e0" - --2I6UYICDbak + --qLE9ygFP5e0 Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1981,7 +2016,7 @@ oh! the humanity! - --2I6UYICDbak + --qLE9ygFP5e0 Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -2012,7 +2047,7 @@ - --2I6UYICDbak + --qLE9ygFP5e0 Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -2022,78 +2057,76 @@ Data ñaca. - --2I6UYICDbak-- + --qLE9ygFP5e0-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_2190548604" -_ queue.go:308 Queue.SendLoop MFSKPJHbfUU: from from -_ queue.go:367 Queue.SendLoop MFSKPJHbfUU: nodomain sent -_ queue.go:367 Queue.SendLoop MFSKPJHbfUU: am@loco sent -_ queue.go:367 Queue.SendLoop MFSKPJHbfUU: x@remote sent -_ queue.go:342 Queue.SendLoop MFSKPJHbfUU: all done ---- PASS: TestBasic (0.02s) + testlib.go:32: test directory: "/tmp/testlib_3901056626" +_ queue.go:308 Queue.SendLoop prIwf0ygD4w: from from +_ queue.go:367 Queue.SendLoop prIwf0ygD4w: nodomain sent +_ queue.go:367 Queue.SendLoop prIwf0ygD4w: am@loco sent +_ queue.go:367 Queue.SendLoop prIwf0ygD4w: x@remote sent +_ queue.go:342 Queue.SendLoop prIwf0ygD4w: all done +--- PASS: TestBasic (0.03s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_1184989881" -_ queue.go:308 Queue.SendLoop PFnXZ023iFU: from from@loco -_ queue.go:467 Queue.SendLoop PFnXZ023iFU: queued DSN: LBpX7b9qSiQ -_ queue.go:342 Queue.SendLoop PFnXZ023iFU: all done -_ queue.go:308 Queue.SendLoop LBpX7b9qSiQ: from <> -_ queue.go:367 Queue.SendLoop LBpX7b9qSiQ: from@loco sent -_ queue.go:342 Queue.SendLoop LBpX7b9qSiQ: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_1184989881/m:LBpX7b9qSiQ": remove /tmp/testlib_1184989881/m:LBpX7b9qSiQ: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_2178110611" +_ queue.go:308 Queue.SendLoop MG_QueGIMUU: from from@loco +_ queue.go:467 Queue.SendLoop MG_QueGIMUU: queued DSN: Ssj51mdFUtU +_ queue.go:342 Queue.SendLoop MG_QueGIMUU: all done +_ queue.go:308 Queue.SendLoop Ssj51mdFUtU: from <> +_ queue.go:367 Queue.SendLoop Ssj51mdFUtU: from@loco sent +_ queue.go:342 Queue.SendLoop Ssj51mdFUtU: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_3949015678" -_ queue.go:308 Queue.SendLoop UjlIq6ZwLCE: from from -_ queue.go:367 Queue.SendLoop UjlIq6ZwLCE: ata@hualpa sent -_ queue.go:367 Queue.SendLoop UjlIq6ZwLCE: pq@loco sent -_ queue.go:367 Queue.SendLoop UjlIq6ZwLCE: rs@loco sent + testlib.go:32: test directory: "/tmp/testlib_631531812" +_ queue.go:308 Queue.SendLoop -4Bj0lyEu0A: from from +_ queue.go:367 Queue.SendLoop -4Bj0lyEu0A: ata@hualpa sent +_ queue.go:367 Queue.SendLoop -4Bj0lyEu0A: pq@loco sent +_ queue.go:367 Queue.SendLoop -4Bj0lyEu0A: rs@loco sent +_ queue.go:342 Queue.SendLoop -4Bj0lyEu0A: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_3941888439" -_ queue.go:342 Queue.SendLoop UjlIq6ZwLCE: all done + testlib.go:32: test directory: "/tmp/testlib_101078528" _ queue.go:164 Queue.Put from: error: queue full -_ queue.go:308 Queue.SendLoop bTrBKMZ4tKA: from from --- PASS: TestFullQueue (0.00s) -_ queue.go:367 Queue.SendLoop bTrBKMZ4tKA: to sent === RUN TestPipes -_ queue.go:375 Queue.SendLoop bTrBKMZ4tKA: error: failed to write: open /tmp/testlib_3941888439/.m:bTrBKMZ4tKA1331001068: no such file or directory - testlib.go:32: test directory: "/tmp/testlib_3684728342" -_ queue.go:342 Queue.SendLoop bTrBKMZ4tKA: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_3941888439/m:bTrBKMZ4tKA": remove /tmp/testlib_3941888439/m:bTrBKMZ4tKA: no such file or directory ---- PASS: TestPipes (0.00s) + testlib.go:32: test directory: "/tmp/testlib_1281612583" +_ queue.go:308 Queue.SendLoop inGSWeVE4Z4: from from +_ queue.go:367 Queue.SendLoop inGSWeVE4Z4: to sent +_ queue.go:375 Queue.SendLoop inGSWeVE4Z4: error: failed to write: open /tmp/testlib_101078528/.m:inGSWeVE4Z41075128720: no such file or directory +_ queue.go:342 Queue.SendLoop inGSWeVE4Z4: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_101078528/m:inGSWeVE4Z4": remove /tmp/testlib_101078528/m:inGSWeVE4Z4: no such file or directory +--- PASS: TestPipes (0.02s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_3892625573" -_ queue.go:308 Queue.SendLoop lytlRzhV-g0: from from@loco -_ queue.go:367 Queue.SendLoop lytlRzhV-g0: to@to sent -_ queue.go:342 Queue.SendLoop lytlRzhV-g0: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_3892625573/m:lytlRzhV-g0": remove /tmp/testlib_3892625573/m:lytlRzhV-g0: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_4054894222" +_ queue.go:308 Queue.SendLoop MI2GEja0bW4: from from@loco +_ queue.go:367 Queue.SendLoop MI2GEja0bW4: to@to sent +_ queue.go:342 Queue.SendLoop MI2GEja0bW4: all done --- PASS: TestSerialization (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.034s +ok blitiri.com.ar/go/chasquid/internal/queue 0.110s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_2838570102" + testlib.go:32: test directory: "/tmp/testlib_1750738622" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_4146407763" + testlib.go:32: test directory: "/tmp/testlib_2467105418" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_1989365184" + testlib.go:32: test directory: "/tmp/testlib_4181417205" --- PASS: TestWriteFileWithFailingOp (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_471746465" + testlib.go:32: test directory: "/tmp/testlib_569785573" --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.006s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.028s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.003s +ok blitiri.com.ar/go/chasquid/internal/set 0.036s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -2107,17 +2140,17 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.02s) +--- PASS: TestLineTooLong (0.14s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.026s -_ server.go:296 Server listening on 127.0.0.1:45075 (SMTP) -2025-07-06 06:33:08.347340 daemon listening on 127.0.0.1:45075 -_ server.go:296 Server listening on 127.0.0.1:40437 (submission) -2025-07-06 06:33:08.347467 daemon listening on 127.0.0.1:40437 -_ server.go:296 Server listening on 127.0.0.1:35209 (submission+TLS) -2025-07-06 06:33:08.347534 daemon listening on 127.0.0.1:35209 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.184s +? blitiri.com.ar/go/chasquid/internal/trace [no test files] +_ server.go:296 Server listening on 127.0.0.1:33807 (SMTP) +2024-06-03 00:13:02.235691 daemon listening on 127.0.0.1:33807 +_ server.go:296 Server listening on 127.0.0.1:41965 (submission) +2024-06-03 00:13:02.235828 daemon listening on 127.0.0.1:41965 +_ server.go:296 Server listening on 127.0.0.1:34237 (submission+TLS) +2024-06-03 00:13:02.235885 daemon listening on 127.0.0.1:34237 === RUN TestSecLevel -_ conn.go:209 SMTP.Conn 127.0.0.1:41044: 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) @@ -2133,182 +2166,183 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:722 SMTP.Conn 127.0.0.1:44010: Queued from from@from to [to@localhost] - rc_yQf6gxZM -2025-07-06 06:33:08.450944 rc_yQf6gxZM from=from@from queued ip=127.0.0.1:44010 to=[to@localhost] -_ queue.go:308 Queue.SendLoop rc_yQf6gxZM: from from@from -_ queue.go:367 Queue.SendLoop rc_yQf6gxZM: testuser@localhost sent -2025-07-06 06:33:08.451041 rc_yQf6gxZM from=from@from to=testuser@localhost sent +_ conn.go:209 SMTP.Conn 127.0.0.1:56894: error: error completing TLS handshake: EOF +_ conn.go:722 SMTP.Conn 127.0.0.1:48628: Queued from from@from to [to@localhost] - n0cuVR2swfo +2024-06-03 00:13:02.341453 n0cuVR2swfo from=from@from queued ip=127.0.0.1:48628 to=[to@localhost] +_ queue.go:308 Queue.SendLoop n0cuVR2swfo: from from@from +_ queue.go:367 Queue.SendLoop n0cuVR2swfo: testuser@localhost sent +2024-06-03 00:13:02.341586 n0cuVR2swfo from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop n0cuVR2swfo: all done +2024-06-03 00:13:02.341918 n0cuVR2swfo from=from@from all done --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ queue.go:342 Queue.SendLoop rc_yQf6gxZM: all done -2025-07-06 06:33:08.451225 rc_yQf6gxZM from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:44016: Queued from from@from to [to@localhost] - w051X01F2m0 -2025-07-06 06:33:08.459489 w051X01F2m0 from=from@from queued ip=127.0.0.1:44016 to=[to@localhost] -_ queue.go:308 Queue.SendLoop w051X01F2m0: from from@from -_ queue.go:367 Queue.SendLoop w051X01F2m0: testuser@localhost sent -2025-07-06 06:33:08.459582 w051X01F2m0 from=from@from to=testuser@localhost sent ---- PASS: TestSimpleTLS (0.01s) +_ conn.go:722 SMTP.Conn 127.0.0.1:48632: Queued from from@from to [to@localhost] - tzICfhY1lOM +2024-06-03 00:13:02.358629 tzICfhY1lOM from=from@from queued ip=127.0.0.1:48632 to=[to@localhost] +_ queue.go:308 Queue.SendLoop tzICfhY1lOM: from from@from +_ queue.go:367 Queue.SendLoop tzICfhY1lOM: testuser@localhost sent +2024-06-03 00:13:02.358808 tzICfhY1lOM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop tzICfhY1lOM: all done +2024-06-03 00:13:02.359349 tzICfhY1lOM from=from@from all done +--- PASS: TestSimpleTLS (0.02s) === RUN TestManyEmails -_ queue.go:342 Queue.SendLoop w051X01F2m0: all done -2025-07-06 06:33:08.459771 w051X01F2m0 from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:44026: Queued from from@from to [to@localhost] - f8QZUvtzjMo -2025-07-06 06:33:08.468321 f8QZUvtzjMo from=from@from queued ip=127.0.0.1:44026 to=[to@localhost] -_ queue.go:308 Queue.SendLoop f8QZUvtzjMo: from from@from -_ queue.go:367 Queue.SendLoop f8QZUvtzjMo: testuser@localhost sent -2025-07-06 06:33:08.468438 f8QZUvtzjMo from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop f8QZUvtzjMo: all done -2025-07-06 06:33:08.468655 f8QZUvtzjMo from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:44026: Queued from from@from to [to@localhost] - QriF75ERkyg -2025-07-06 06:33:08.469179 QriF75ERkyg from=from@from queued ip=127.0.0.1:44026 to=[to@localhost] -_ queue.go:308 Queue.SendLoop QriF75ERkyg: from from@from -_ queue.go:367 Queue.SendLoop QriF75ERkyg: testuser@localhost sent -2025-07-06 06:33:08.469285 QriF75ERkyg from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop QriF75ERkyg: all done -2025-07-06 06:33:08.469472 QriF75ERkyg from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:44026: Queued from from@from to [to@localhost] - qaE2u8eWgU8 -2025-07-06 06:33:08.470035 qaE2u8eWgU8 from=from@from queued ip=127.0.0.1:44026 to=[to@localhost] -_ queue.go:308 Queue.SendLoop qaE2u8eWgU8: from from@from -_ queue.go:367 Queue.SendLoop qaE2u8eWgU8: testuser@localhost sent -2025-07-06 06:33:08.470160 qaE2u8eWgU8 from=from@from to=testuser@localhost sent ---- PASS: TestManyEmails (0.01s) +_ conn.go:722 SMTP.Conn 127.0.0.1:48646: Queued from from@from to [to@localhost] - y53zBHuBJME +2024-06-03 00:13:02.375069 y53zBHuBJME from=from@from queued ip=127.0.0.1:48646 to=[to@localhost] +_ queue.go:308 Queue.SendLoop y53zBHuBJME: from from@from +_ queue.go:367 Queue.SendLoop y53zBHuBJME: testuser@localhost sent +2024-06-03 00:13:02.375173 y53zBHuBJME from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop y53zBHuBJME: all done +2024-06-03 00:13:02.375671 y53zBHuBJME from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:48646: Queued from from@from to [to@localhost] - K45Li_hAjsM +2024-06-03 00:13:02.377091 K45Li_hAjsM from=from@from queued ip=127.0.0.1:48646 to=[to@localhost] +_ queue.go:308 Queue.SendLoop K45Li_hAjsM: from from@from +_ queue.go:367 Queue.SendLoop K45Li_hAjsM: testuser@localhost sent +2024-06-03 00:13:02.377227 K45Li_hAjsM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop K45Li_hAjsM: all done +2024-06-03 00:13:02.377780 K45Li_hAjsM from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:48646: Queued from from@from to [to@localhost] - K7Wuc4gN9sI +2024-06-03 00:13:02.379769 K7Wuc4gN9sI from=from@from queued ip=127.0.0.1:48646 to=[to@localhost] +_ queue.go:308 Queue.SendLoop K7Wuc4gN9sI: from from@from +_ queue.go:367 Queue.SendLoop K7Wuc4gN9sI: testuser@localhost sent +2024-06-03 00:13:02.379939 K7Wuc4gN9sI from=from@from to=testuser@localhost sent +--- PASS: TestManyEmails (0.02s) === RUN TestAuth -_ queue.go:342 Queue.SendLoop qaE2u8eWgU8: all done -2025-07-06 06:33:08.470455 qaE2u8eWgU8 from=from@from all done -2025-07-06 06:33:08.579635 127.0.0.1:43818 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:43818: Queued from testuser@localhost to [to@localhost] - vPqdqPtU3LQ -2025-07-06 06:33:08.580434 vPqdqPtU3LQ from=testuser@localhost queued ip=127.0.0.1:43818 to=[to@localhost] -_ queue.go:308 Queue.SendLoop vPqdqPtU3LQ: from testuser@localhost -_ queue.go:367 Queue.SendLoop vPqdqPtU3LQ: testuser@localhost sent -2025-07-06 06:33:08.580605 vPqdqPtU3LQ from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuth (0.11s) +_ queue.go:342 Queue.SendLoop K7Wuc4gN9sI: all done +2024-06-03 00:13:02.380366 K7Wuc4gN9sI from=from@from all done +2024-06-03 00:13:02.673002 127.0.0.1:35016 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:35016: Queued from testuser@localhost to [to@localhost] - lb1cyuzeCYI +2024-06-03 00:13:02.677063 lb1cyuzeCYI from=testuser@localhost queued ip=127.0.0.1:35016 to=[to@localhost] +_ queue.go:308 Queue.SendLoop lb1cyuzeCYI: from testuser@localhost +_ queue.go:367 Queue.SendLoop lb1cyuzeCYI: testuser@localhost sent +2024-06-03 00:13:02.677176 lb1cyuzeCYI from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop lb1cyuzeCYI: all done +2024-06-03 00:13:02.677689 lb1cyuzeCYI from=testuser@localhost all done +--- PASS: TestAuth (0.30s) === RUN TestSubmissionWithoutAuth -_ queue.go:342 Queue.SendLoop vPqdqPtU3LQ: all done -2025-07-06 06:33:08.580878 vPqdqPtU3LQ from=testuser@localhost all done -_ conn.go:318 SMTP.Conn 127.0.0.1:43826: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated ---- PASS: TestSubmissionWithoutAuth (0.01s) +_ conn.go:318 SMTP.Conn 127.0.0.1:35020: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +--- PASS: TestSubmissionWithoutAuth (0.03s) === RUN TestAuthOnTLS -2025-07-06 06:33:08.699755 127.0.0.1:41058 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:41058: Queued from testuser@localhost to [to@localhost] - K84W8lTKMeE -2025-07-06 06:33:08.700696 K84W8lTKMeE from=testuser@localhost queued ip=127.0.0.1:41058 to=[to@localhost] -_ queue.go:308 Queue.SendLoop K84W8lTKMeE: from testuser@localhost -_ queue.go:367 Queue.SendLoop K84W8lTKMeE: testuser@localhost sent -2025-07-06 06:33:08.700823 K84W8lTKMeE from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnTLS (0.11s) +2024-06-03 00:13:02.899667 127.0.0.1:56910 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:56910: Queued from testuser@localhost to [to@localhost] - x5o4vChmP9w +2024-06-03 00:13:02.901117 x5o4vChmP9w from=testuser@localhost queued ip=127.0.0.1:56910 to=[to@localhost] +_ queue.go:308 Queue.SendLoop x5o4vChmP9w: from testuser@localhost +_ queue.go:367 Queue.SendLoop x5o4vChmP9w: testuser@localhost sent +2024-06-03 00:13:02.901272 x5o4vChmP9w from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop x5o4vChmP9w: all done +2024-06-03 00:13:02.901795 x5o4vChmP9w from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.20s) === RUN TestAuthOnSMTP -_ queue.go:342 Queue.SendLoop K84W8lTKMeE: all done -2025-07-06 06:33:08.701052 K84W8lTKMeE from=testuser@localhost all done -2025-07-06 06:33:08.812911 127.0.0.1:44042 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:44042: Queued from testuser@localhost to [to@localhost] - bmxJouA5_J4 -2025-07-06 06:33:08.813683 bmxJouA5_J4 from=testuser@localhost queued ip=127.0.0.1:44042 to=[to@localhost] -_ queue.go:308 Queue.SendLoop bmxJouA5_J4: from testuser@localhost -_ queue.go:367 Queue.SendLoop bmxJouA5_J4: testuser@localhost sent -2025-07-06 06:33:08.813792 bmxJouA5_J4 from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnSMTP (0.11s) +2024-06-03 00:13:03.128022 127.0.0.1:48650 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:48650: Queued from testuser@localhost to [to@localhost] - w5e5m7JMy8I +2024-06-03 00:13:03.129638 w5e5m7JMy8I from=testuser@localhost queued ip=127.0.0.1:48650 to=[to@localhost] +_ queue.go:308 Queue.SendLoop w5e5m7JMy8I: from testuser@localhost +_ queue.go:367 Queue.SendLoop w5e5m7JMy8I: testuser@localhost sent +2024-06-03 00:13:03.129776 w5e5m7JMy8I from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnSMTP (0.23s) === RUN TestBrokenAuth -_ queue.go:342 Queue.SendLoop bmxJouA5_J4: all done -2025-07-06 06:33:08.814028 bmxJouA5_J4 from=testuser@localhost all done -_ conn.go:1193 SMTP.Conn 127.0.0.1:43828: error: error authenticating \"user\"@\"broken\": failed to auth -2025-07-06 06:33:08.926735 127.0.0.1:43828 auth failed for user@broken -_ conn.go:318 SMTP.Conn 127.0.0.1:43828: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:318 SMTP.Conn 127.0.0.1:43828: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.11s) +_ queue.go:342 Queue.SendLoop w5e5m7JMy8I: all done +2024-06-03 00:13:03.140128 w5e5m7JMy8I from=testuser@localhost all done +_ conn.go:1193 SMTP.Conn 127.0.0.1:35030: error: error authenticating \"user\"@\"broken\": failed to auth +2024-06-03 00:13:03.284324 127.0.0.1:35030 auth failed for user@broken +_ conn.go:318 SMTP.Conn 127.0.0.1:35030: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:318 SMTP.Conn 127.0.0.1:35030: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.15s) === RUN TestWrongMailParsing -_ conn.go:318 SMTP.Conn 127.0.0.1:44056: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44056: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44056: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44056: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:44060: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44060: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44060: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44060: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:44062: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44062: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44062: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44062: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:44070: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44070: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44070: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:44084: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44084: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44084: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44084: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:44098: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:44098: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:44098: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:44098: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.00s) +_ conn.go:318 SMTP.Conn 127.0.0.1:48666: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48666: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48666: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48666: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:48680: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48680: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48680: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48680: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:48696: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48696: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48696: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48696: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:48710: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48710: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48710: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48710: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:48712: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48712: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48712: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48712: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:48718: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:48718: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:48718: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:48718: 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:44112: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:318 SMTP.Conn 127.0.0.1:48730: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption ---- PASS: TestRcptOption (0.01s) +--- PASS: TestRcptOption (0.02s) === RUN TestRelayForbidden -2025-07-06 06:33:08.940986 127.0.0.1:44132 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:318 SMTP.Conn 127.0.0.1:44132: error: RCPT failed: 503 5.7.1 Relay not allowed +2024-06-03 00:13:03.315133 127.0.0.1:48744 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:318 SMTP.Conn 127.0.0.1:48744: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2025-07-06 06:33:09.055199 127.0.0.1:43836 auth succeeded for testuser@localhost -_ conn.go:318 SMTP.Conn 127.0.0.1:43836: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.12s) +2024-06-03 00:13:03.496320 127.0.0.1:35038 auth succeeded for testuser@localhost +_ conn.go:318 SMTP.Conn 127.0.0.1:35038: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.21s) === RUN TestRcptBrokenExists -_ conn.go:622 SMTP.Conn 127.0.0.1:44148: error: error checking if user \"to@broken\" exists: failed to check if user exists -2025-07-06 06:33:09.068382 127.0.0.1:44148 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:44148: error: RCPT failed: 451 4.4.3 Temporary error checking address ---- PASS: TestRcptBrokenExists (0.01s) +_ conn.go:622 SMTP.Conn 127.0.0.1:48746: error: error checking if user \"to@broken\" exists: failed to check if user exists +2024-06-03 00:13:03.565983 127.0.0.1:48746 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:48746: error: RCPT failed: 451 4.4.3 Temporary error checking address +--- PASS: TestRcptBrokenExists (0.04s) === RUN TestRcptUserDoesNotExist -2025-07-06 06:33:09.077884 127.0.0.1:44164 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:318 SMTP.Conn 127.0.0.1:44164: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) ---- PASS: TestRcptUserDoesNotExist (0.01s) +2024-06-03 00:13:03.610950 127.0.0.1:48754 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:318 SMTP.Conn 127.0.0.1:48754: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +--- PASS: TestRcptUserDoesNotExist (0.04s) === RUN TestTooMuchData -_ conn.go:722 SMTP.Conn 127.0.0.1:44176: Queued from from@from to [to@localhost] - y-G-J3AyJBQ -2025-07-06 06:33:09.320989 y-G-J3AyJBQ from=from@from queued ip=127.0.0.1:44176 to=[to@localhost] -_ queue.go:308 Queue.SendLoop y-G-J3AyJBQ: from from@from -_ queue.go:367 Queue.SendLoop y-G-J3AyJBQ: testuser@localhost sent -2025-07-06 06:33:09.321134 y-G-J3AyJBQ from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop y-G-J3AyJBQ: all done -2025-07-06 06:33:09.342492 y-G-J3AyJBQ from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:44176: Queued from from@from to [to@localhost] - axZGA7kp2iE -2025-07-06 06:33:09.572591 axZGA7kp2iE from=from@from queued ip=127.0.0.1:44176 to=[to@localhost] -_ queue.go:308 Queue.SendLoop axZGA7kp2iE: from from@from -_ queue.go:367 Queue.SendLoop axZGA7kp2iE: testuser@localhost sent -2025-07-06 06:33:09.572711 axZGA7kp2iE from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop axZGA7kp2iE: all done -2025-07-06 06:33:09.586069 axZGA7kp2iE from=from@from all done -_ conn.go:318 SMTP.Conn 127.0.0.1:44176: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:722 SMTP.Conn 127.0.0.1:44176: Queued from from@from to [to@localhost] - Sgi2ijIQiHk -2025-07-06 06:33:10.028396 Sgi2ijIQiHk from=from@from queued ip=127.0.0.1:44176 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Sgi2ijIQiHk: from from@from -_ queue.go:367 Queue.SendLoop Sgi2ijIQiHk: testuser@localhost sent -2025-07-06 06:33:10.028552 Sgi2ijIQiHk from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.95s) +_ conn.go:722 SMTP.Conn 127.0.0.1:48766: Queued from from@from to [to@localhost] - Mrwep1EdPFg +2024-06-03 00:13:04.142632 Mrwep1EdPFg from=from@from queued ip=127.0.0.1:48766 to=[to@localhost] +_ queue.go:308 Queue.SendLoop Mrwep1EdPFg: from from@from +_ queue.go:367 Queue.SendLoop Mrwep1EdPFg: testuser@localhost sent +2024-06-03 00:13:04.142894 Mrwep1EdPFg from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop Mrwep1EdPFg: all done +2024-06-03 00:13:04.209704 Mrwep1EdPFg from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:48766: Queued from from@from to [to@localhost] - maoFZOjtVM8 +2024-06-03 00:13:04.692647 maoFZOjtVM8 from=from@from queued ip=127.0.0.1:48766 to=[to@localhost] +_ queue.go:308 Queue.SendLoop maoFZOjtVM8: from from@from +_ queue.go:367 Queue.SendLoop maoFZOjtVM8: testuser@localhost sent +2024-06-03 00:13:04.692861 maoFZOjtVM8 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop maoFZOjtVM8: all done +2024-06-03 00:13:04.736011 maoFZOjtVM8 from=from@from all done +_ conn.go:318 SMTP.Conn 127.0.0.1:48766: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:722 SMTP.Conn 127.0.0.1:48766: Queued from from@from to [to@localhost] - 9t_xlQ3dPrQ +2024-06-03 00:13:05.854020 9t_xlQ3dPrQ from=from@from queued ip=127.0.0.1:48766 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 9t_xlQ3dPrQ: from from@from +_ queue.go:367 Queue.SendLoop 9t_xlQ3dPrQ: testuser@localhost sent +2024-06-03 00:13:05.854314 9t_xlQ3dPrQ from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (2.25s) === RUN TestSimpleCommands -_ conn.go:318 SMTP.Conn 127.0.0.1:44188: 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:44188: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:318 SMTP.Conn 127.0.0.1:40176: 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:40176: 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:44200: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:348 SMTP.Conn 127.0.0.1:44200: error: exiting with error: line too long +_ conn.go:318 SMTP.Conn 127.0.0.1:40192: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:348 SMTP.Conn 127.0.0.1:40192: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset ---- PASS: TestReset (0.00s) +--- PASS: TestReset (0.01s) === RUN TestRepeatedStartTLS -_ conn.go:318 SMTP.Conn 127.0.0.1:44222: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestRepeatedStartTLS (0.01s) +_ conn.go:318 SMTP.Conn 127.0.0.1:40204: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestRepeatedStartTLS (0.03s) === RUN TestStartTLSOnTLS -_ queue.go:342 Queue.SendLoop Sgi2ijIQiHk: all done -2025-07-06 06:33:10.041742 Sgi2ijIQiHk from=from@from all done -_ conn.go:318 SMTP.Conn 127.0.0.1:41074: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestStartTLSOnTLS (0.01s) +_ queue.go:342 Queue.SendLoop 9t_xlQ3dPrQ: all done +2024-06-03 00:13:05.902632 9t_xlQ3dPrQ from=from@from all done +_ conn.go:318 SMTP.Conn 127.0.0.1:40526: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestStartTLSOnTLS (0.02s) === RUN TestAddDKIMSigner - testlib.go:32: test directory: "/tmp/testlib_3288741373" ---- PASS: TestAddDKIMSigner (0.02s) + testlib.go:32: test directory: "/tmp/testlib_1242359906" +--- PASS: TestAddDKIMSigner (0.05s) === RUN FuzzConnection --- PASS: FuzzConnection (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.983s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 4.226s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -2323,19 +2357,19 @@ sts_test.go:210: policy404: got error as expected: HTTP response status code: 404 sts_test.go:218: version99: got expected error: unknown policy version sts_test.go:226: domErr: got expected error: error for testing purposes ---- PASS: TestFetch (0.00s) +--- PASS: TestFetch (0.01s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.03s) +--- PASS: TestPolicyTooBig (0.13s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_83975071" + testlib.go:32: test directory: "/tmp/testlib_67438620" 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) +--- PASS: TestCacheBasics (0.02s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_2345805006" + testlib.go:32: test directory: "/tmp/testlib_2478176561" 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} @@ -2344,13 +2378,13 @@ sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} --- PASS: TestCacheBadData (0.00s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_2163261386" + testlib.go:32: test directory: "/tmp/testlib_2755513782" 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_252827126" + testlib.go:32: test directory: "/tmp/testlib_2321011966" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -2360,9 +2394,9 @@ === RUN TestHTTPGet --- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.049s +ok blitiri.com.ar/go/chasquid/internal/sts 0.220s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1734809588" + testlib.go:32: test directory: "/tmp/testlib_821590087" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -2373,54 +2407,54 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_2510383294" + testlib.go:32: test directory: "/tmp/testlib_3801555773" --- PASS: TestRewrite (0.00s) === RUN TestGetFreePort --- PASS: TestGetFreePort (0.00s) === RUN TestWaitFor --- PASS: TestWaitFor (0.02s) === RUN TestGenerateCert - testlib.go:32: test directory: "/tmp/testlib_2384264019" ---- PASS: TestGenerateCert (0.05s) + testlib.go:32: test directory: "/tmp/testlib_3704335512" +--- PASS: TestGenerateCert (0.19s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.10s) +--- PASS: TestGenerateCertBadDir (0.30s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.174s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.536s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.002s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.028s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test1585048804" - userdb_test.go:37: file: "/tmp/userdb_test3241457220" + userdb_test.go:37: file: "/tmp/userdb_test2779288173" + userdb_test.go:37: file: "/tmp/userdb_test2608869029" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test700406946" ---- PASS: TestWrite (0.52s) + userdb_test.go:37: file: "/tmp/userdb_test2739428866" +--- PASS: TestWrite (1.05s) === RUN TestNew ---- PASS: TestNew (0.07s) +--- PASS: TestNew (0.13s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test692431985" + userdb_test.go:37: file: "/tmp/userdb_test2945723685" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test3214172325" + userdb_test.go:37: file: "/tmp/userdb_test2123391254" --- PASS: TestPlainScheme (0.00s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test3809871270" + userdb_test.go:37: file: "/tmp/userdb_test3522173617" --- PASS: TestDeniedScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test1932843378" + userdb_test.go:37: file: "/tmp/userdb_test394787964" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test45141718" ---- PASS: TestRemoveUser (0.07s) + userdb_test.go:37: file: "/tmp/userdb_test1627777664" +--- PASS: TestRemoveUser (0.12s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test4025243563" ---- PASS: TestExists (0.07s) + userdb_test.go:37: file: "/tmp/userdb_test2344636333" +--- PASS: TestExists (0.13s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 0.741s +ok blitiri.com.ar/go/chasquid/internal/userdb 1.452s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -2465,10 +2499,10 @@ dh_fixperms -O--buildsystem=golang dh_missing -O--buildsystem=golang dh_strip -a -O--buildsystem=golang -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/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-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 @@ -2485,12 +2519,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/16646/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/16646/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/34942 and its subdirectories -I: Current time: Sat Jul 5 18:33:28 -12 2025 -I: pbuilder-time-stamp: 1751783608 +I: removing directory /srv/workspace/pbuilder/16646 and its subdirectories +I: Current time: Mon Jun 3 14:13:46 +14 2024 +I: pbuilder-time-stamp: 1717373626