Diff of the two buildlogs: -- --- b1/build.log 2024-10-25 16:45:40.138669868 +0000 +++ b2/build.log 2024-10-25 16:47:37.350497765 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Oct 25 04:41:29 -12 2024 -I: pbuilder-time-stamp: 1729874489 +I: Current time: Fri Nov 28 13:08:40 +14 2025 +I: pbuilder-time-stamp: 1764284920 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -27,52 +27,84 @@ 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/3270454/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/292610/tmp/hooks/D01_modify_environment starting +debug: Running on ionos5-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Nov 27 23:08 /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/292610/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/292610/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='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=20 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="32" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.32(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=42 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='f409b9e7f361441abd895d5a351799a5' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='3270454' - PS1='# ' - PS2='> ' + INVOCATION_ID=2bb7a4a011da4cf096d18f0d5c48e4a6 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=292610 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.vDFwUQu0/pbuilderrc_Yt29 --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.vDFwUQu0/b1 --logfile b1/build.log chasquid_1.14.0-2.dsc' - SUDO_GID='111' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132: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.vDFwUQu0/pbuilderrc_35dl --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.vDFwUQu0/b2 --logfile b2/build.log chasquid_1.14.0-2.dsc' + SUDO_GID=110 + SUDO_UID=105 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://213.165.73.152:3128 I: uname -a - Linux ionos11-amd64 6.1.0-26-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.112-1 (2024-09-30) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.10.11+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.10.11-1~bpo12+1 (2024-10-03) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Aug 4 21:30 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/3270454/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Aug 4 2024 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/292610/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -175,7 +207,7 @@ Get: 43 http://deb.debian.org/debian unstable/main amd64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 44 http://deb.debian.org/debian unstable/main amd64 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 45 http://deb.debian.org/debian unstable/main amd64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 73.8 MB in 9s (8146 kB/s) +Fetched 73.8 MB in 1s (71.5 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 ... 19947 files and directories currently installed.) @@ -370,7 +402,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/292610/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/292610/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 @@ -390,259 +426,259 @@ 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-x86_64-linux-gnu && go install -trimpath -v -p 20 -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 -internal/godebugs -internal/byteorder -encoding -internal/goos -internal/race -internal/msan -internal/goexperiment -internal/cpu -log/internal -internal/nettrace -internal/coverage/rtcov + cd obj-x86_64-linux-gnu && go install -trimpath -v -p 42 -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 internal/goarch -internal/profilerecord -internal/itoa -unicode/utf8 -sync/atomic -internal/runtime/atomic -internal/asan -google.golang.org/protobuf/internal/flags unicode/utf16 -container/ring container/list +google.golang.org/protobuf/internal/flags +container/ring +vendor/golang.org/x/crypto/internal/alias crypto/internal/alias +log/internal +unicode/utf8 +math/bits +internal/asan +vendor/golang.org/x/crypto/cryptobyte/asn1 +internal/nettrace +internal/msan +internal/byteorder +internal/race +blitiri.com.ar/go/chasquid/internal/set cmp +internal/goos internal/unsafeheader +internal/goexperiment +unicode +internal/profilerecord +internal/itoa +internal/godebugs +internal/coverage/rtcov crypto/subtle -crypto/internal/boring/sig -math/bits internal/runtime/syscall -vendor/golang.org/x/crypto/cryptobyte/asn1 -unicode -vendor/golang.org/x/crypto/internal/alias -blitiri.com.ar/go/chasquid/internal/set +internal/cpu +encoding +internal/runtime/atomic +crypto/internal/boring/sig +sync/atomic +runtime/internal/math internal/abi internal/chacha8rand -runtime/internal/math -runtime/internal/sys google.golang.org/protobuf/internal/set -internal/runtime/exithook +runtime/internal/sys internal/bytealg math +internal/runtime/exithook internal/stringslite runtime -internal/reflectlite +internal/weak iter +internal/reflectlite sync -internal/weak -slices maps -internal/bisect +slices internal/singleflight internal/testlog +internal/bisect google.golang.org/protobuf/internal/pragma runtime/cgo errors -sort -internal/godebug -strconv -math/rand internal/oserror -vendor/golang.org/x/net/dns/dnsmessage -crypto/internal/edwards25519/field path +internal/godebug io +sort +strconv +crypto/internal/edwards25519/field +vendor/golang.org/x/net/dns/dnsmessage math/rand/v2 -crypto/internal/nistec/fiat syscall -golang.org/x/text/internal/tag -internal/concurrent -crypto/internal/edwards25519 -unique -crypto/rc4 -reflect +crypto/internal/nistec/fiat +math/rand +strings hash crypto/internal/randutil bytes -strings +crypto/internal/edwards25519 +internal/concurrent hash/fnv hash/crc32 +unique +golang.org/x/text/internal/tag +crypto/rc4 crypto -crypto/md5 +reflect net/netip -crypto/cipher -vendor/golang.org/x/text/transform +crypto/md5 golang.org/x/text/transform +vendor/golang.org/x/text/transform +crypto/cipher bufio +net/http/internal/ascii html regexp/syntax -net/http/internal/ascii golang.org/x/text/runes golang.org/x/text/width -crypto/des crypto/internal/boring -crypto/aes +crypto/des crypto/hmac -crypto/sha256 +crypto/aes crypto/sha512 crypto/sha1 +crypto/sha256 vendor/golang.org/x/crypto/hkdf golang.org/x/crypto/pbkdf2 -regexp +internal/syscall/execenv internal/syscall/unix time -internal/syscall/execenv -internal/fmtsort -encoding/binary +regexp io/fs context internal/poll +embed +internal/filepathlite +internal/fmtsort +encoding/binary +google.golang.org/protobuf/internal/editiondefaults +crypto/internal/nistec +os encoding/base64 +golang.org/x/crypto/scrypt vendor/golang.org/x/crypto/chacha20 vendor/golang.org/x/crypto/internal/poly1305 -golang.org/x/crypto/scrypt golang.org/x/sys/unix -internal/filepathlite -embed encoding/pem -google.golang.org/protobuf/internal/editiondefaults -crypto/internal/nistec -os crypto/ecdh -fmt +google.golang.org/protobuf/internal/detrand io/ioutil path/filepath -os/signal -blitiri.com.ar/go/chasquid/internal/safeio -google.golang.org/protobuf/internal/detrand internal/sysinfo +blitiri.com.ar/go/chasquid/internal/safeio +os/signal vendor/golang.org/x/sys/cpu +fmt net -vendor/golang.org/x/crypto/chacha20poly1305 vendor/golang.org/x/crypto/sha3 +vendor/golang.org/x/crypto/chacha20poly1305 os/exec -golang.org/x/term -log +google.golang.org/protobuf/internal/errors flag -go/token -compress/flate google.golang.org/protobuf/internal/version -encoding/json -net/url -text/template/parse encoding/hex -vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/net/http2/hpack -mime -mime/quotedprintable net/http/internal -google.golang.org/protobuf/internal/errors blitiri.com.ar/go/chasquid/internal/envelope -golang.org/x/text/unicode/norm -golang.org/x/text/internal/language -blitiri.com.ar/go/chasquid/internal/tlsconst -math/big +net/url +mime/quotedprintable +compress/flate +vendor/golang.org/x/net/http2/hpack text/tabwriter -runtime/trace +text/template/parse +mime runtime/debug +blitiri.com.ar/go/chasquid/internal/tlsconst +golang.org/x/text/internal/language +log +encoding/json +runtime/trace +go/token +math/big +vendor/golang.org/x/text/unicode/norm +golang.org/x/text/unicode/norm google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/reflect/protoreflect -golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/bidi -testing +golang.org/x/text/unicode/bidi compress/gzip -golang.org/x/text/secure/bidirule -runtime/pprof -internal/profile +testing golang.org/x/text/internal/language/compact -text/template -vendor/golang.org/x/text/secure/bidirule -golang.org/x/text/language -golang.org/x/net/idna -vendor/golang.org/x/net/idna +internal/profile +runtime/pprof google.golang.org/protobuf/internal/encoding/messageset -google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/order -google.golang.org/protobuf/internal/genid google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt +google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/descopts -google.golang.org/protobuf/internal/encoding/text +google.golang.org/protobuf/internal/genid +vendor/golang.org/x/text/secure/bidirule +text/template google.golang.org/protobuf/reflect/protoregistry -google.golang.org/protobuf/internal/encoding/defval +golang.org/x/text/language +golang.org/x/term +golang.org/x/text/secure/bidirule +google.golang.org/protobuf/internal/encoding/text +golang.org/x/net/idna +vendor/golang.org/x/net/idna google.golang.org/protobuf/proto -golang.org/x/text/internal -golang.org/x/text/cases -crypto/elliptic +google.golang.org/protobuf/internal/encoding/defval crypto/internal/boring/bbig +crypto/rand +crypto/elliptic crypto/internal/bigmod -crypto/dsa encoding/asn1 -crypto/rand -html/template -crypto/ed25519 +crypto/dsa +golang.org/x/text/internal +golang.org/x/text/cases crypto/internal/hpke +crypto/ed25519 crypto/internal/mlkem768 +crypto/rsa google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc -crypto/rsa -golang.org/x/text/secure/precis -vendor/golang.org/x/crypto/cryptobyte +html/template crypto/x509/pkix +vendor/golang.org/x/crypto/cryptobyte +golang.org/x/text/secure/precis blitiri.com.ar/go/chasquid/internal/protoio crypto/ecdsa blitiri.com.ar/go/chasquid/internal/normalize google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/internal/impl -log/syslog -net/textproto -vendor/golang.org/x/net/http/httpproxy blitiri.com.ar/go/chasquid/internal/haproxy +vendor/golang.org/x/net/http/httpproxy +log/syslog +blitiri.com.ar/go/systemd blitiri.com.ar/go/spf +net/textproto crypto/x509 -blitiri.com.ar/go/systemd blitiri.com.ar/go/log vendor/golang.org/x/net/http/httpguts -mime/multipart blitiri.com.ar/go/chasquid/internal/dovecot +mime/multipart net/mail blitiri.com.ar/go/chasquid/cmd/mda-lmtp -blitiri.com.ar/go/chasquid/internal/dkim -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 +blitiri.com.ar/go/chasquid/internal/dkim +crypto/tls net/http/httptrace -net/smtp blitiri.com.ar/go/chasquid/internal/testlib -blitiri.com.ar/go/chasquid/internal/smtp +net/smtp net/http +blitiri.com.ar/go/chasquid/internal/smtp golang.org/x/net/context/ctxhttp -blitiri.com.ar/go/chasquid/internal/nettrace net/http/pprof expvar +blitiri.com.ar/go/chasquid/internal/nettrace blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/trace -blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/localrpc +blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/maillog blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/cmd/chasquid-util -blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/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-x86_64-linux-gnu && go test -vet=off -v -p 20 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-x86_64-linux-gnu && go test -vet=off -v -p 42 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] @@ -666,7 +702,7 @@ === RUN TestTooMuchRecursionOnCatchAll --- PASS: TestTooMuchRecursionOnCatchAll (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.01s) +--- PASS: TestAddFile (0.00s) === RUN TestRichFile --- PASS: TestRichFile (0.00s) === RUN TestManyFiles @@ -692,7 +728,9 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.054s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.019s +? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] +? blitiri.com.ar/go/chasquid/internal/trace [no test files] === 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 @@ -705,7 +743,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.50s) +--- PASS: TestAuthenticate (0.33s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends @@ -738,46 +776,45 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.695s +ok blitiri.com.ar/go/chasquid/internal/auth 0.516s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_1326611728" + testlib.go:32: test directory: "/tmp/testlib_1943477584" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_345687847" ---- PASS: TestFullConfig (0.01s) + testlib.go:32: test directory: "/tmp/testlib_3205043341" +--- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_1915073888" + testlib.go:32: test directory: "/tmp/testlib_2711208267" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_426230010" + testlib.go:32: test directory: "/tmp/testlib_4064579204" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.028s -? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] +ok blitiri.com.ar/go/chasquid/internal/config 0.009s === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_3101693800" ---- PASS: TestMDA (0.01s) + testlib.go:32: test directory: "/tmp/testlib_964642187" +--- PASS: TestMDA (0.00s) === RUN TestMDATimeout _ mda.go:73 Courier.MDA to@local: error: operation timed out --- PASS: TestMDATimeout (0.10s) === 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.01s) +--- PASS: TestMDABadCommandLine (0.00s) === 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_2948526042" - testlib.go:32: test directory: "/tmp/testlib_1425765483" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::37765: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::37765: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_936049937" + testlib.go:32: test directory: "/tmp/testlib_3140093223" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::38397: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::38397: 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:" @@ -785,26 +822,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.03s) +--- PASS: TestSMTP (0.06s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_792042133" - testlib.go:32: test directory: "/tmp/testlib_112462556" + testlib.go:32: test directory: "/tmp/testlib_2212497888" + testlib.go:32: test directory: "/tmp/testlib_1142695126" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:35102->127.0.0.1:41881: 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:35102->127.0.0.1:41881: 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:35102->127.0.0.1:41881: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:44236->127.0.0.1:38273: 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:44236->127.0.0.1:38273: 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:44236->127.0.0.1:38273: 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:35102->127.0.0.1:41881: i/o timeout) - testlib.go:32: test directory: "/tmp/testlib_1644300635" - testlib.go:32: test directory: "/tmp/testlib_2501128180" + smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:44236->127.0.0.1:38273: i/o timeout) + testlib.go:32: test directory: "/tmp/testlib_851635914" + testlib.go:32: test directory: "/tmp/testlib_2619791586" 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_3991840527" - testlib.go:32: test directory: "/tmp/testlib_94802674" + testlib.go:32: test directory: "/tmp/testlib_1061759371" + testlib.go:32: test directory: "/tmp/testlib_3249221925" 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 +849,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_877406958" - testlib.go:32: test directory: "/tmp/testlib_3429011823" + testlib.go:32: test directory: "/tmp/testlib_2549564719" + testlib.go:32: test directory: "/tmp/testlib_135958387" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -822,8 +859,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_3292680124" - testlib.go:32: test directory: "/tmp/testlib_2727044441" + testlib.go:32: test directory: "/tmp/testlib_3150708248" + testlib.go:32: test directory: "/tmp/testlib_719814785" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -832,9 +869,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.64s) +--- PASS: TestSMTPErrors (1.14s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_851695850" + testlib.go:32: test directory: "/tmp/testlib_975831149" _ 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) @@ -849,8 +886,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_3774179007" - testlib.go:32: test directory: "/tmp/testlib_1458458100" + testlib.go:32: test directory: "/tmp/testlib_87790443" + testlib.go:32: test directory: "/tmp/testlib_1652039165" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -861,18 +898,18 @@ 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_2584287432" + testlib.go:32: test directory: "/tmp/testlib_154676773" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" _ domaininfo.go:145 DomainInfo.Outgoing to: error: to outgoing denied: PLAIN < TLS_INSECURE _ smtp.go:185 Courier.SMTP to@to: error: Security level check failed (level:PLAIN) + fakeserver_test.go:105: fakeServer exiting: EOF _ smtp.go:101 Courier.SMTP to@to: error: \"localhost\" returned transient error: Security level check failed (level:PLAIN) _ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: Security level check failed (level:PLAIN)) - fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestTLS (0.10s) +--- PASS: TestTLS (0.08s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_2141471583" - testlib.go:32: test directory: "/tmp/testlib_3235392856" + testlib.go:32: test directory: "/tmp/testlib_2997146565" + testlib.go:32: test directory: "/tmp/testlib_1157618405" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -885,10 +922,10 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestTLSError (0.06s) +--- PASS: TestTLSError (0.03s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_1321194336" - testlib.go:32: test directory: "/tmp/testlib_2710643718" + testlib.go:32: test directory: "/tmp/testlib_1096288372" + testlib.go:32: test directory: "/tmp/testlib_4038647262" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -896,7 +933,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_1853725327" + testlib.go:32: test directory: "/tmp/testlib_1568887323" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -907,9 +944,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.37s) +--- PASS: TestSTSPolicyEnforcement (0.25s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.405s +ok blitiri.com.ar/go/chasquid/internal/courier 1.675s === RUN TestStringToCanonicalization --- PASS: TestStringToCanonicalization (0.00s) === RUN TestSimpleBody @@ -945,7 +982,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:[0xc0002b9b60 0xc0002b9bc0]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xc000249f20 0xc000274000]} 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; @@ -989,7 +1026,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:[0xc000320420 0xc000320480 0xc0003204e0 0xc000320540 0xc0003205a0]} + file_test.go:56: Want result: &{Found:5 Valid:5 Results:[0xc0002747e0 0xc000274840 0xc0002748a0 0xc000274900 0xc000274960]} 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; @@ -1101,7 +1138,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:[0xc000112a80]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xc000275560]} 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; @@ -1115,7 +1152,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:[0xc000112ea0 0xc000112f00]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xc000275980 0xc0002759e0]} 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; @@ -1149,7 +1186,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:[0xc000113500 0xc000113560]} + file_test.go:56: Want result: &{Found:2 Valid:1 Results:[0xc0002de060 0xc0002de0c0]} 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; @@ -1183,7 +1220,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:[0xc000113bc0 0xc000113c20]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xc0002de720 0xc0002de780]} 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; @@ -1230,7 +1267,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:[0xc0003de3c0 0xc0003de420 0xc0003de480]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xc0002deea0 0xc0002def00 0xc0002def60]} 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; @@ -1290,7 +1327,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:[0xc0003dec60 0xc0003decc0 0xc0003ded20]} + file_test.go:56: Want result: &{Found:3 Valid:3 Results:[0xc0002df740 0xc0002df7a0 0xc0002df800]} 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; @@ -1350,7 +1387,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:[0xc0003df560 0xc0003df5c0]} + file_test.go:56: Want result: &{Found:2 Valid:2 Results:[0xc00034e0c0 0xc00034e120]} 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; @@ -1396,7 +1433,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:[0xc0003dfc80]} + file_test.go:56: Want result: &{Found:1 Valid:0 Results:[0xc00034e7e0]} 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; @@ -1417,7 +1454,7 @@ context.go:17: Resulting hash: "kFTkVTyR9JWKX/Stl1+sUIuAgitRiOf7MwTBy5a2/ZA=" context.go:17: PK [ed25519:4afa0f4faf766d5a]: Strict domain check failed: "example.com" != "sub.example.com" ("test@sub.example.com") context.go:17: Found 1 signatures, 0 valid ---- PASS: TestFromFiles (0.03s) +--- PASS: TestFromFiles (0.01s) --- PASS: TestFromFiles/testdata/01-rfc8463 (0.00s) --- PASS: TestFromFiles/testdata/02-too_many_headers (0.00s) --- PASS: TestFromFiles/testdata/03-bad_message (0.00s) @@ -1425,7 +1462,7 @@ --- 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.01s) + --- 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/11-strict_domain_check_fail (0.00s) @@ -1452,18 +1489,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=1729874701; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "ltDJ95fphDY/r+h+yjcEhSYyq7zIiGPNPR7fNoCQmN4=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "NI/xOQ4TELsv2o5SXtIKLommAgigutwUtfjiYQQCPp0=" context.go:17: Found DKIM-Signature header: v=1; a=rsa-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1729874701; + d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=bAsmWdHgfvYO1b9JjEeQHKpo1R7yRtBWoLCf7IovRxQiin538nW12tVxeolhQCTTzar/Zv - wBHYWW04q3M//eHIENNlnozKzGQ7aOzHIDopblRx0EGfXMv5GEdsBTCcCg2xROwMy4fsZ9 - 7ZofWTsS60sZzcnjpfHuPa636oiftVGk5AuY8OdSN3JM/pMabctQApBKI/lYBIl2p0FbZ5 - +tidR7PoN53smU+sG+2QAZz3dYyLIZOGFJzqDEOPd/FwX3iFhpzqXgtrDJ8q3xSbChz2v8 - lCdObozprEoVDH6flhkBz0viEaLsO4hzaJKy/0qStLe4HgcQpofrwCQDpEnhig==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA2zWT0DqghqOeB+Q3a2WL5WLVE14VOy1lhCMJrG+aMpkmQKgt6iXq2+IK+vCcPD0mr2I79Vyb0PBnTq3eTcG30dXlvdcxAyXZpP2AcZiZ+b6w4JnDEq3DGvmG9lixuDm1ow5h1AAnKa8fht4kY0CNmaOy1VknkFEwzisTEEJS1yBYmjHidnYS/fs68P8ld0b0Rw7UdPyc4/iz+Vr2JlHsJTdLMlw4XpvdjZ8HOtVwYnnnw6+2BblfTjnqEg0VNAjc5KurTTq/bOKeYw9KV2NFqBmq7GxyTwDhzH1JFKGHJxmU9M0e8KeDUH7bj2EIG1r/wn0QluKvMlu6LThwCJ8sHQIDAQAB" + b=t9U/voPdUpJ4BXp5XTg/EGrgUZvwTDTiUdtutxSWug36Kmz0SD4bwdJ+0Vbc53a/WU8rdg + H+bB2xABwSilIHQpACl4ZbLeIaGmPA81z+suqQrqlNTnrI5RKzPS91weCwvaFoEx9A87eC + Eu0mvoeKXTZwPYP8Ex33ABVuAIDP1QzGANkzO8MsOHArQUzpHxpsSuA5kTtryQ5Y887WOv + wMHhxWj9m9IwRp2M5US4k3cN0DR8k/W8yXfBoD07u+PtabIkiWqmq47Ezx7E4aZd820m+q + x0yHMLgkay+HRHUJwrH1UTmHC8VV+/pyh+r7RTgl5mOAGQc2FOGe5jSPl+U6Vg==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; p=MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA3Gc6N9LYTlKv403G7i+aySSsjmZp57eM0XH6UTaHBQSh81oJl/l2IvhdXZTYbObjeB+0s4/ijCltV7yfJgRFhc9FLP0XwZxAMbaiLo6ejvlMJvGug6MUSAVZOpz3Kl0rGI0kPG71YM1BZA9CaIewkdHO890avQAsHUGZxCgyW5qIVNQMbqYY8pp7PMwXoxxu6PYMaXZVEOOeYvRdN4hok8P7+WBuEo2ae6wLWr+MockQockLG8e5KRd8sWJozNwQA+n21mOUqaokaFfg9THD5efGalEa5nWARsS43ERW7jUvZsCAAy5Q/Zgazl47EZC/zfEZEFEsG+TINzE0t6zABQIDAQAB" 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" @@ -1471,11 +1508,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=1729874701; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "ltDJ95fphDY/r+h+yjcEhSYyq7zIiGPNPR7fNoCQmN4=" + context.go:17: Hashing header: "dkim-signature:v=1; a=rsa-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "NI/xOQ4TELsv2o5SXtIKLommAgigutwUtfjiYQQCPp0=" context.go:17: PK [rsa:30820122300d0609]: Verification succeeded context.go:17: Found 1 signatures, 1 valid ---- PASS: TestSignRSA (0.41s) +--- PASS: TestSignRSA (0.73s) === RUN TestSignEd25519 context.go:17: Signing for example.com / test with ed25519-sha256 context.go:17: Hashing header: "from:Joe SixPack \r\n" @@ -1483,25 +1520,25 @@ context.go:17: Hashing header: "date:Fri, 11 Jul 2003 21:00:37 -0700 (PDT)\r\n" context.go:17: Hashing header: "to:Suzie Q \r\n" context.go:17: Hashing header: "message-id:<20030712040037.46341.5F8J@football.example.com>\r\n" - context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1729874701; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "wkVXrMdE9f8IFaijw/vy8rx0EvfU5haG4pOOEJIL2MU=" + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "ArUXjGLaYc7/Qatx2nhcpr2odm0hP/GPCpHsP5cQq0E=" context.go:17: Found DKIM-Signature header: v=1; a=ed25519-sha256; c=relaxed/relaxed; - d=example.com; s=test; t=1729874701; + d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; - b=xvEPGyO7Tdq9zROxurw9bvSHuPHHlVMlRQNOrzLgOVIK1UCxOK5SphTQLFIs+TXG7XFdsI - PiDijTAgbX+O+lAQ==; - context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=4Yr6Zb26vksycAkNNnq2Dl2jz5RyRUk8Hf8C7STWGRw=" - context.go:17: Parsed public key: [ed25519:e18afa65bdbabe4b] + b=p/eTbn+jdld65BN51FSqQSqUX07k5hXdTVGmpNlCNcAbIUPb+jnxilqVMxcJe3XzXBHPTY + JMI57KR9tp3BJMBg==; + context.go:17: TXT record for "test._domainkey.example.com": "v=DKIM1; k=ed25519; p=Fe5M+KfAN0IWLHMTVWqPF7UXOXW/NsAp7hPe/ph2pXw=" + context.go:17: Parsed public key: [ed25519:15ee4cf8a7c03742] 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=1729874701; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" - context.go:17: Resulting hash: "wkVXrMdE9f8IFaijw/vy8rx0EvfU5haG4pOOEJIL2MU=" - context.go:17: PK [ed25519:e18afa65bdbabe4b]: Verification succeeded + context.go:17: Hashing header: "dkim-signature:v=1; a=ed25519-sha256; c=relaxed/relaxed; d=example.com; s=test; t=1764285016; h=from:subject:date:to:message-id:from:subject:date:to:cc:message-id; bh=2jUSOH9NhtVGCQWNr9BrIAPreKQjO6Sn7XIkfJVOzv8=; b=;" + context.go:17: Resulting hash: "ArUXjGLaYc7/Qatx2nhcpr2odm0hP/GPCpHsP5cQq0E=" + context.go:17: PK [ed25519:15ee4cf8a7c03742]: Verification succeeded context.go:17: Found 1 signatures, 1 valid --- PASS: TestSignEd25519 (0.00s) === RUN TestSignBadMessage @@ -1700,25 +1737,25 @@ --- PASS: FuzzParsePublicKey/seed#12 (0.00s) --- PASS: FuzzParsePublicKey/seed#13 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dkim 0.520s +ok blitiri.com.ar/go/chasquid/internal/dkim 0.759s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_2672718641" + testlib.go:32: test directory: "/tmp/testlib_2846509784" _ 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.15s) +--- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_98439073" + testlib.go:32: test directory: "/tmp/testlib_1680826890" _ 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.36s) +--- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_1367691909" + testlib.go:32: test directory: "/tmp/testlib_2197606757" _ 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 @@ -1731,29 +1768,29 @@ _ domaininfo.go:145 DomainInfo.Outgoing ssip: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:109 DomainInfo.Incoming ssip: error: ssip incoming denied: PLAIN < TLS_SECURE _ domaininfo.go:145 DomainInfo.Outgoing ssip: error: ssip outgoing denied: PLAIN < TLS_SECURE ---- PASS: TestProgressions (0.40s) +--- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_2925565634" + testlib.go:32: test directory: "/tmp/testlib_4136211583" _ 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.11s) +--- PASS: TestErrors (0.00s) === RUN TestDirectoryErrors - testlib.go:32: test directory: "/tmp/testlib_2614321765" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_2614321765/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_2614321765/db/.s:d12724303228: no such file or directory ---- PASS: TestDirectoryErrors (0.03s) + testlib.go:32: test directory: "/tmp/testlib_3425282697" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_3425282697/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_3425282697/db/.s:d13632248408: no such file or directory +--- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 1.065s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.015s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_2184205179" + testlib.go:32: test directory: "/tmp/testlib_579607063" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.016s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.007s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -1761,13 +1798,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.013s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.004s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.025s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.007s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -1792,21 +1829,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.013s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.004s === RUN TestBadServer - asm_amd64.s:1700: FakeServer &{{0xc0000e3200}}: accepted - asm_amd64.s:1700: FakeServer &{{0xc0000e3200}}: readRequest: "" "" / EOF - asm_amd64.s:1700: FakeServer &{{0xc0000e3200}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-2554691135/rpc.sock->@: write: broken pipe - asm_amd64.s:1700: FakeServer &{{0xc0000e3200}}: closing - asm_amd64.s:1700: FakeServer &{{0xc0000e3380}}: accepted - asm_amd64.s:1700: FakeServer &{{0xc0000e3380}}: readRequest: "Echo" "" / - asm_amd64.s:1700: FakeServer &{{0xc0000e3380}}: writeMessage("xxx"): 3 - asm_amd64.s:1700: FakeServer &{{0xc0000e3380}}: closing + asm_amd64.s:1700: FakeServer &{{0xc000280080}}: accepted + asm_amd64.s:1700: FakeServer &{{0xc000280080}}: readRequest: "" "" / EOF + asm_amd64.s:1700: FakeServer &{{0xc000280080}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-664345774/rpc.sock->@: write: broken pipe + asm_amd64.s:1700: FakeServer &{{0xc000280080}}: closing + asm_amd64.s:1700: FakeServer &{{0xc000280200}}: accepted + asm_amd64.s:1700: FakeServer &{{0xc000280200}}: readRequest: "Echo" "" / + asm_amd64.s:1700: FakeServer &{{0xc000280200}}: writeMessage("xxx"): 3 + asm_amd64.s:1700: FakeServer &{{0xc000280200}}: closing --- PASS: TestBadServer (0.01s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-1676209195/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-4185435982/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1814,7 +1851,7 @@ _ 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-1676209195/sock: error: Accept error: accept unix /tmp/rpc-test-1676209195/sock: use of closed network connection +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-4185435982/sock: error: Accept error: accept unix /tmp/rpc-test-4185435982/sock: use of closed network connection --- PASS: TestEndToEnd (0.01s) --- PASS: TestEndToEnd/Echo (0.00s) --- PASS: TestEndToEnd/Echo#01 (0.00s) @@ -1829,7 +1866,7 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.043s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.030s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -1837,7 +1874,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.200s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.007s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1847,13 +1884,13 @@ === RUN TestHTTP --- PASS: TestHTTP (0.01s) === RUN TestHTTPLong ---- PASS: TestHTTPLong (0.01s) +--- PASS: TestHTTPLong (0.00s) === RUN TestHTTPErrors ---- PASS: TestHTTPErrors (0.02s) +--- PASS: TestHTTPErrors (0.01s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.01s) +--- PASS: TestHTTPUroboro (0.00s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.01s) +--- PASS: TestHTTPDeep (0.00s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler @@ -1873,7 +1910,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.076s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.034s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1933,40 +1970,40 @@ --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.008s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.007s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_4169779039" + testlib.go:32: test directory: "/tmp/testlib_4061530000" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_2107424052" + testlib.go:32: test directory: "/tmp/testlib_283124063" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_114747659" + testlib.go:32: test directory: "/tmp/testlib_2251452539" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_4247418664" + testlib.go:32: test directory: "/tmp/testlib_1749132668" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_934894399" + testlib.go:32: test directory: "/tmp/testlib_710451176" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.010s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.008s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Fri, 25 Oct 2024 16:45:04 +0000 + Message-ID: + Date: Thu, 27 Nov 2025 23:10:16 +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="b4Mng9ScBLY" + boundary="y7I6E83vSEo" - --b4Mng9ScBLY + --y7I6E83vSEo Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1991,7 +2028,7 @@ oh! the humanity! - --b4Mng9ScBLY + --y7I6E83vSEo Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -2022,7 +2059,7 @@ - --b4Mng9ScBLY + --y7I6E83vSEo Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -2032,77 +2069,77 @@ Data ñaca. - --b4Mng9ScBLY-- + --y7I6E83vSEo-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_2713841903" -_ queue.go:308 Queue.SendLoop hBmusSUiH0Y: from from -_ queue.go:367 Queue.SendLoop hBmusSUiH0Y: nodomain sent -_ queue.go:367 Queue.SendLoop hBmusSUiH0Y: x@remote sent -_ queue.go:367 Queue.SendLoop hBmusSUiH0Y: am@loco sent -_ queue.go:342 Queue.SendLoop hBmusSUiH0Y: all done + testlib.go:32: test directory: "/tmp/testlib_544064953" +_ queue.go:308 Queue.SendLoop A4MYpFyZMyc: from from +_ queue.go:367 Queue.SendLoop A4MYpFyZMyc: nodomain sent +_ queue.go:367 Queue.SendLoop A4MYpFyZMyc: am@loco sent +_ queue.go:367 Queue.SendLoop A4MYpFyZMyc: x@remote sent +_ queue.go:342 Queue.SendLoop A4MYpFyZMyc: all done --- PASS: TestBasic (0.02s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_4277724873" -_ queue.go:308 Queue.SendLoop 5w-Wu_pw8UY: from from@loco -_ queue.go:467 Queue.SendLoop 5w-Wu_pw8UY: queued DSN: tgNuTJQi0zQ -_ queue.go:342 Queue.SendLoop 5w-Wu_pw8UY: all done -_ queue.go:308 Queue.SendLoop tgNuTJQi0zQ: from <> -_ queue.go:367 Queue.SendLoop tgNuTJQi0zQ: from@loco sent -_ queue.go:342 Queue.SendLoop tgNuTJQi0zQ: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_4277724873/m:tgNuTJQi0zQ": remove /tmp/testlib_4277724873/m:tgNuTJQi0zQ: no such file or directory ---- PASS: TestDSNOnTimeout (0.01s) + testlib.go:32: test directory: "/tmp/testlib_3611982821" +_ queue.go:308 Queue.SendLoop S-8ja-9SnIg: from from@loco +_ queue.go:467 Queue.SendLoop S-8ja-9SnIg: queued DSN: gTNMIUsXAKQ +_ queue.go:342 Queue.SendLoop S-8ja-9SnIg: all done +_ queue.go:308 Queue.SendLoop gTNMIUsXAKQ: from <> +_ queue.go:367 Queue.SendLoop gTNMIUsXAKQ: from@loco sent +--- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_1948400599" -_ queue.go:308 Queue.SendLoop B8nsA13qEK4: from from -_ queue.go:367 Queue.SendLoop B8nsA13qEK4: ata@hualpa sent -_ queue.go:367 Queue.SendLoop B8nsA13qEK4: pq@loco sent -_ queue.go:367 Queue.SendLoop B8nsA13qEK4: rs@loco sent -_ queue.go:342 Queue.SendLoop B8nsA13qEK4: all done +_ queue.go:342 Queue.SendLoop gTNMIUsXAKQ: all done + testlib.go:32: test directory: "/tmp/testlib_3525410201" +_ queue.go:308 Queue.SendLoop 4IflKeKj3qM: from from +_ queue.go:367 Queue.SendLoop 4IflKeKj3qM: ata@hualpa sent +_ queue.go:367 Queue.SendLoop 4IflKeKj3qM: pq@loco sent +_ queue.go:367 Queue.SendLoop 4IflKeKj3qM: rs@loco sent +_ queue.go:342 Queue.SendLoop 4IflKeKj3qM: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_2916949529" + testlib.go:32: test directory: "/tmp/testlib_2864529924" _ queue.go:164 Queue.Put from: error: queue full ---- PASS: TestFullQueue (0.01s) +_ queue.go:308 Queue.SendLoop YTPmWWmkWgs: from from +--- PASS: TestFullQueue (0.00s) +_ queue.go:367 Queue.SendLoop YTPmWWmkWgs: to sent === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_2884493024" -_ queue.go:308 Queue.SendLoop S64Pk2PcHyU: from from -_ queue.go:367 Queue.SendLoop S64Pk2PcHyU: to sent -_ queue.go:375 Queue.SendLoop S64Pk2PcHyU: error: failed to write: open /tmp/testlib_2916949529/.m:S64Pk2PcHyU3662041402: no such file or directory -_ queue.go:342 Queue.SendLoop S64Pk2PcHyU: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_2916949529/m:S64Pk2PcHyU": remove /tmp/testlib_2916949529/m:S64Pk2PcHyU: no such file or directory +_ queue.go:375 Queue.SendLoop YTPmWWmkWgs: error: failed to write: open /tmp/testlib_2864529924/.m:YTPmWWmkWgs2089086896: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_3382711775" +_ queue.go:342 Queue.SendLoop YTPmWWmkWgs: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_2864529924/m:YTPmWWmkWgs": remove /tmp/testlib_2864529924/m:YTPmWWmkWgs: no such file or directory --- PASS: TestPipes (0.00s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_432481508" -_ queue.go:308 Queue.SendLoop 4T0cmlfoFQg: from from@loco -_ queue.go:367 Queue.SendLoop 4T0cmlfoFQg: to@to sent -_ queue.go:342 Queue.SendLoop 4T0cmlfoFQg: all done ---- PASS: TestSerialization (0.01s) + testlib.go:32: test directory: "/tmp/testlib_2297981211" +_ queue.go:308 Queue.SendLoop HEksGOGRS54: from from@loco +_ queue.go:367 Queue.SendLoop HEksGOGRS54: to@to sent +_ queue.go:342 Queue.SendLoop HEksGOGRS54: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_2297981211/m:HEksGOGRS54": remove /tmp/testlib_2297981211/m:HEksGOGRS54: no such file or directory +--- PASS: TestSerialization (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.074s +ok blitiri.com.ar/go/chasquid/internal/queue 0.033s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_3936635883" + testlib.go:32: test directory: "/tmp/testlib_3310698806" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_3079431706" + testlib.go:32: test directory: "/tmp/testlib_4069145186" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_3475203651" + testlib.go:32: test directory: "/tmp/testlib_3649699513" --- PASS: TestWriteFileWithFailingOp (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_3871054087" + testlib.go:32: test directory: "/tmp/testlib_1166026386" --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.014s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.006s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.007s +ok blitiri.com.ar/go/chasquid/internal/set 0.003s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -2116,17 +2153,16 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.03s) +--- PASS: TestLineTooLong (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.043s -? blitiri.com.ar/go/chasquid/internal/trace [no test files] -_ server.go:296 Server listening on 127.0.0.1:35183 (submission+TLS) -2024-10-25 16:45:04.432648 daemon listening on 127.0.0.1:35183 -_ server.go:296 Server listening on 127.0.0.1:36579 (SMTP) -2024-10-25 16:45:04.432759 daemon listening on 127.0.0.1:36579 -_ server.go:296 Server listening on 127.0.0.1:38765 (submission) -2024-10-25 16:45:04.432798 daemon listening on 127.0.0.1:38765 -_ conn.go:209 SMTP.Conn 127.0.0.1:44592: error: error completing TLS handshake: EOF +ok blitiri.com.ar/go/chasquid/internal/smtp 0.020s +_ server.go:296 Server listening on 127.0.0.1:46153 (submission+TLS) +2025-11-27 23:10:16.509203 daemon listening on 127.0.0.1:46153 +_ server.go:296 Server listening on 127.0.0.1:46351 (SMTP) +2025-11-27 23:10:16.509318 daemon listening on 127.0.0.1:46351 +_ server.go:296 Server listening on 127.0.0.1:44241 (submission) +2025-11-27 23:10:16.509366 daemon listening on 127.0.0.1:44241 +_ conn.go:209 SMTP.Conn 127.0.0.1:55044: error: error completing TLS handshake: EOF === RUN TestSecLevel _ 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 @@ -2143,184 +2179,182 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:722 SMTP.Conn 127.0.0.1:33938: Queued from from@from to [to@localhost] - APkdzbSS0W8 -2024-10-25 16:45:04.540737 APkdzbSS0W8 from=from@from queued ip=127.0.0.1:33938 to=[to@localhost] -_ queue.go:308 Queue.SendLoop APkdzbSS0W8: from from@from -_ queue.go:367 Queue.SendLoop APkdzbSS0W8: testuser@localhost sent -2024-10-25 16:45:04.540980 APkdzbSS0W8 from=from@from to=testuser@localhost sent +_ conn.go:722 SMTP.Conn 127.0.0.1:57688: Queued from from@from to [to@localhost] - EAPX2qSQHU4 +2025-11-27 23:10:16.613225 EAPX2qSQHU4 from=from@from queued ip=127.0.0.1:57688 to=[to@localhost] +_ queue.go:308 Queue.SendLoop EAPX2qSQHU4: from from@from +_ queue.go:367 Queue.SendLoop EAPX2qSQHU4: testuser@localhost sent +2025-11-27 23:10:16.613408 EAPX2qSQHU4 from=from@from to=testuser@localhost sent --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ queue.go:342 Queue.SendLoop APkdzbSS0W8: all done -2024-10-25 16:45:04.544183 APkdzbSS0W8 from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:33944: Queued from from@from to [to@localhost] - p2uWb7VEm4o -2024-10-25 16:45:04.545503 p2uWb7VEm4o from=from@from queued ip=127.0.0.1:33944 to=[to@localhost] -_ queue.go:308 Queue.SendLoop p2uWb7VEm4o: from from@from -_ queue.go:367 Queue.SendLoop p2uWb7VEm4o: testuser@localhost sent -2024-10-25 16:45:04.545667 p2uWb7VEm4o from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop EAPX2qSQHU4: all done +2025-11-27 23:10:16.613740 EAPX2qSQHU4 from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:57696: Queued from from@from to [to@localhost] - Yf9vjb1KlTg +2025-11-27 23:10:16.615971 Yf9vjb1KlTg from=from@from queued ip=127.0.0.1:57696 to=[to@localhost] +_ queue.go:308 Queue.SendLoop Yf9vjb1KlTg: from from@from +_ queue.go:367 Queue.SendLoop Yf9vjb1KlTg: testuser@localhost sent +2025-11-27 23:10:16.616071 Yf9vjb1KlTg from=from@from to=testuser@localhost sent --- PASS: TestSimpleTLS (0.00s) === RUN TestManyEmails -_ queue.go:342 Queue.SendLoop p2uWb7VEm4o: all done -2024-10-25 16:45:04.545941 p2uWb7VEm4o from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:33960: Queued from from@from to [to@localhost] - KPMsU_Av3_s -2024-10-25 16:45:04.550999 KPMsU_Av3_s from=from@from queued ip=127.0.0.1:33960 to=[to@localhost] -_ queue.go:308 Queue.SendLoop KPMsU_Av3_s: from from@from -_ queue.go:367 Queue.SendLoop KPMsU_Av3_s: testuser@localhost sent -2024-10-25 16:45:04.551170 KPMsU_Av3_s from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop KPMsU_Av3_s: all done -2024-10-25 16:45:04.551608 KPMsU_Av3_s from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:33960: Queued from from@from to [to@localhost] - gOVTsJg4su4 -2024-10-25 16:45:04.555140 gOVTsJg4su4 from=from@from queued ip=127.0.0.1:33960 to=[to@localhost] -_ queue.go:308 Queue.SendLoop gOVTsJg4su4: from from@from -_ queue.go:367 Queue.SendLoop gOVTsJg4su4: testuser@localhost sent -2024-10-25 16:45:04.555304 gOVTsJg4su4 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop gOVTsJg4su4: all done -2024-10-25 16:45:04.556915 gOVTsJg4su4 from=from@from all done -_ conn.go:722 SMTP.Conn 127.0.0.1:33960: Queued from from@from to [to@localhost] - ZLRpNzybhjU -2024-10-25 16:45:04.557037 ZLRpNzybhjU from=from@from queued ip=127.0.0.1:33960 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ZLRpNzybhjU: from from@from -_ queue.go:367 Queue.SendLoop ZLRpNzybhjU: testuser@localhost sent -2024-10-25 16:45:04.557186 ZLRpNzybhjU from=from@from to=testuser@localhost sent ---- PASS: TestManyEmails (0.01s) +_ queue.go:342 Queue.SendLoop Yf9vjb1KlTg: all done +2025-11-27 23:10:16.616328 Yf9vjb1KlTg from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:57708: Queued from from@from to [to@localhost] - tSAH0QjLtD0 +2025-11-27 23:10:16.618434 tSAH0QjLtD0 from=from@from queued ip=127.0.0.1:57708 to=[to@localhost] +_ queue.go:308 Queue.SendLoop tSAH0QjLtD0: from from@from +_ queue.go:367 Queue.SendLoop tSAH0QjLtD0: testuser@localhost sent +2025-11-27 23:10:16.618536 tSAH0QjLtD0 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop tSAH0QjLtD0: all done +2025-11-27 23:10:16.618733 tSAH0QjLtD0 from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:57708: Queued from from@from to [to@localhost] - 3kWWfYTsUfo +2025-11-27 23:10:16.619168 3kWWfYTsUfo from=from@from queued ip=127.0.0.1:57708 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 3kWWfYTsUfo: from from@from +_ queue.go:367 Queue.SendLoop 3kWWfYTsUfo: testuser@localhost sent +2025-11-27 23:10:16.619267 3kWWfYTsUfo from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 3kWWfYTsUfo: all done +2025-11-27 23:10:16.619465 3kWWfYTsUfo from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:57708: Queued from from@from to [to@localhost] - LjHIB5hIPa0 +2025-11-27 23:10:16.619916 LjHIB5hIPa0 from=from@from queued ip=127.0.0.1:57708 to=[to@localhost] +_ queue.go:308 Queue.SendLoop LjHIB5hIPa0: from from@from +_ queue.go:367 Queue.SendLoop LjHIB5hIPa0: testuser@localhost sent +2025-11-27 23:10:16.620035 LjHIB5hIPa0 from=from@from to=testuser@localhost sent +--- PASS: TestManyEmails (0.00s) === RUN TestAuth -_ queue.go:342 Queue.SendLoop ZLRpNzybhjU: all done -2024-10-25 16:45:04.557569 ZLRpNzybhjU from=from@from all done -2024-10-25 16:45:04.690470 127.0.0.1:46210 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:46210: Queued from testuser@localhost to [to@localhost] - oJxxH0UsWSU -2024-10-25 16:45:04.695517 oJxxH0UsWSU from=testuser@localhost queued ip=127.0.0.1:46210 to=[to@localhost] -_ queue.go:308 Queue.SendLoop oJxxH0UsWSU: from testuser@localhost -_ queue.go:367 Queue.SendLoop oJxxH0UsWSU: testuser@localhost sent -2024-10-25 16:45:04.697491 oJxxH0UsWSU from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuth (0.14s) +_ queue.go:342 Queue.SendLoop LjHIB5hIPa0: all done +2025-11-27 23:10:16.620300 LjHIB5hIPa0 from=from@from all done +2025-11-27 23:10:16.728559 127.0.0.1:36704 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:36704: Queued from testuser@localhost to [to@localhost] - CjbtRNes2Fs +2025-11-27 23:10:16.729438 CjbtRNes2Fs from=testuser@localhost queued ip=127.0.0.1:36704 to=[to@localhost] +_ queue.go:308 Queue.SendLoop CjbtRNes2Fs: from testuser@localhost +_ queue.go:367 Queue.SendLoop CjbtRNes2Fs: testuser@localhost sent +2025-11-27 23:10:16.729590 CjbtRNes2Fs from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuth (0.11s) === RUN TestSubmissionWithoutAuth -_ queue.go:342 Queue.SendLoop oJxxH0UsWSU: all done -2024-10-25 16:45:04.698733 oJxxH0UsWSU from=testuser@localhost all done -_ conn.go:318 SMTP.Conn 127.0.0.1:46220: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ queue.go:342 Queue.SendLoop CjbtRNes2Fs: all done +2025-11-27 23:10:16.729893 CjbtRNes2Fs from=testuser@localhost all done +_ conn.go:318 SMTP.Conn 127.0.0.1:36718: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.00s) === RUN TestAuthOnTLS -2024-10-25 16:45:04.807114 127.0.0.1:44598 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:44598: Queued from testuser@localhost to [to@localhost] - _0Cqk2_PqWU -2024-10-25 16:45:04.808438 _0Cqk2_PqWU from=testuser@localhost queued ip=127.0.0.1:44598 to=[to@localhost] -_ queue.go:308 Queue.SendLoop _0Cqk2_PqWU: from testuser@localhost -_ queue.go:367 Queue.SendLoop _0Cqk2_PqWU: testuser@localhost sent -2024-10-25 16:45:04.808672 _0Cqk2_PqWU from=testuser@localhost to=testuser@localhost sent +2025-11-27 23:10:16.838240 127.0.0.1:55052 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:55052: Queued from testuser@localhost to [to@localhost] - b1iUg4rdOPE +2025-11-27 23:10:16.839011 b1iUg4rdOPE from=testuser@localhost queued ip=127.0.0.1:55052 to=[to@localhost] +_ queue.go:308 Queue.SendLoop b1iUg4rdOPE: from testuser@localhost +_ queue.go:367 Queue.SendLoop b1iUg4rdOPE: testuser@localhost sent +2025-11-27 23:10:16.839163 b1iUg4rdOPE from=testuser@localhost to=testuser@localhost sent --- PASS: TestAuthOnTLS (0.11s) === RUN TestAuthOnSMTP -_ queue.go:342 Queue.SendLoop _0Cqk2_PqWU: all done -2024-10-25 16:45:04.810749 _0Cqk2_PqWU from=testuser@localhost all done -2024-10-25 16:45:04.932376 127.0.0.1:33976 auth succeeded for testuser@localhost -_ conn.go:722 SMTP.Conn 127.0.0.1:33976: Queued from testuser@localhost to [to@localhost] - VzjqifmpUGs -2024-10-25 16:45:04.933767 VzjqifmpUGs from=testuser@localhost queued ip=127.0.0.1:33976 to=[to@localhost] -_ queue.go:308 Queue.SendLoop VzjqifmpUGs: from testuser@localhost -_ queue.go:367 Queue.SendLoop VzjqifmpUGs: testuser@localhost sent -2024-10-25 16:45:04.934128 VzjqifmpUGs from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnSMTP (0.13s) +_ queue.go:342 Queue.SendLoop b1iUg4rdOPE: all done +2025-11-27 23:10:16.839485 b1iUg4rdOPE from=testuser@localhost all done +2025-11-27 23:10:16.946923 127.0.0.1:57720 auth succeeded for testuser@localhost +_ conn.go:722 SMTP.Conn 127.0.0.1:57720: Queued from testuser@localhost to [to@localhost] - 6heQkbp5_e4 +2025-11-27 23:10:16.947834 6heQkbp5_e4 from=testuser@localhost queued ip=127.0.0.1:57720 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 6heQkbp5_e4: from testuser@localhost +_ queue.go:367 Queue.SendLoop 6heQkbp5_e4: testuser@localhost sent +2025-11-27 23:10:16.948025 6heQkbp5_e4 from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnSMTP (0.11s) === RUN TestBrokenAuth -_ queue.go:342 Queue.SendLoop VzjqifmpUGs: all done -2024-10-25 16:45:04.936603 VzjqifmpUGs from=testuser@localhost all done -_ conn.go:1193 SMTP.Conn 127.0.0.1:46234: error: error authenticating \"user\"@\"broken\": failed to auth -2024-10-25 16:45:05.046714 127.0.0.1:46234 auth failed for user@broken -_ conn.go:318 SMTP.Conn 127.0.0.1:46234: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:318 SMTP.Conn 127.0.0.1:46234: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +_ queue.go:342 Queue.SendLoop 6heQkbp5_e4: all done +2025-11-27 23:10:16.948387 6heQkbp5_e4 from=testuser@localhost all done +_ conn.go:1193 SMTP.Conn 127.0.0.1:36730: error: error authenticating \"user\"@\"broken\": failed to auth +2025-11-27 23:10:17.057911 127.0.0.1:36730 auth failed for user@broken +_ conn.go:318 SMTP.Conn 127.0.0.1:36730: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:318 SMTP.Conn 127.0.0.1:36730: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command --- PASS: TestBrokenAuth (0.11s) === RUN TestWrongMailParsing -_ conn.go:318 SMTP.Conn 127.0.0.1:33982: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:33982: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:33982: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:33982: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:33990: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:33990: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:33990: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:33990: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:33996: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:33996: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:33996: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:33996: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:34008: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:34008: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:34008: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:34008: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:34018: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:34018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:34018: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:34018: error: too many errors, breaking connection -_ conn.go:318 SMTP.Conn 127.0.0.1:34028: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:318 SMTP.Conn 127.0.0.1:34028: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:318 SMTP.Conn 127.0.0.1:34028: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:325 SMTP.Conn 127.0.0.1:34028: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.01s) +_ conn.go:318 SMTP.Conn 127.0.0.1:57730: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57730: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57730: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57730: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:57742: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57742: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57742: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57742: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:57750: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57750: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57750: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57750: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:57760: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57760: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57760: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57760: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:57776: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57776: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57776: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57776: error: too many errors, breaking connection +_ conn.go:318 SMTP.Conn 127.0.0.1:57790: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:318 SMTP.Conn 127.0.0.1:57790: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:318 SMTP.Conn 127.0.0.1:57790: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:325 SMTP.Conn 127.0.0.1:57790: error: too many errors, breaking connection +--- PASS: TestWrongMailParsing (0.00s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:318 SMTP.Conn 127.0.0.1:34050: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:318 SMTP.Conn 127.0.0.1:57812: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.00s) === RUN TestRelayForbidden -2024-10-25 16:45:05.059617 127.0.0.1:34072 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:318 SMTP.Conn 127.0.0.1:34072: error: RCPT failed: 503 5.7.1 Relay not allowed +2025-11-27 23:10:17.065322 127.0.0.1:57842 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:318 SMTP.Conn 127.0.0.1:57842: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2024-10-25 16:45:05.166057 127.0.0.1:46250 auth succeeded for testuser@localhost -_ conn.go:318 SMTP.Conn 127.0.0.1:46250: error: RCPT failed: 452 4.5.3 Too many recipients +2025-11-27 23:10:17.168648 127.0.0.1:36732 auth succeeded for testuser@localhost +_ conn.go:318 SMTP.Conn 127.0.0.1:36732: error: RCPT failed: 452 4.5.3 Too many recipients --- PASS: TestTooManyRecipients (0.11s) === RUN TestRcptBrokenExists -_ conn.go:622 SMTP.Conn 127.0.0.1:34080: error: error checking if user \"to@broken\" exists: failed to check if user exists -2024-10-25 16:45:05.176196 127.0.0.1:34080 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:34080: error: RCPT failed: 451 4.4.3 Temporary error checking address +_ conn.go:622 SMTP.Conn 127.0.0.1:57846: error: error checking if user \"to@broken\" exists: failed to check if user exists +2025-11-27 23:10:17.174240 127.0.0.1:57846 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:57846: error: RCPT failed: 451 4.4.3 Temporary error checking address --- PASS: TestRcptBrokenExists (0.00s) === RUN TestRcptUserDoesNotExist -2024-10-25 16:45:05.179755 127.0.0.1:34088 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:318 SMTP.Conn 127.0.0.1:34088: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +2025-11-27 23:10:17.176071 127.0.0.1:57862 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:318 SMTP.Conn 127.0.0.1:57862: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptUserDoesNotExist (0.00s) === RUN TestTooMuchData -_ conn.go:722 SMTP.Conn 127.0.0.1:34102: Queued from from@from to [to@localhost] - l1STAlTzItA -2024-10-25 16:45:05.371928 l1STAlTzItA from=from@from queued ip=127.0.0.1:34102 to=[to@localhost] -_ queue.go:308 Queue.SendLoop l1STAlTzItA: from from@from -_ queue.go:367 Queue.SendLoop l1STAlTzItA: testuser@localhost sent -2024-10-25 16:45:05.372108 l1STAlTzItA from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop l1STAlTzItA: all done -2024-10-25 16:45:05.399626 l1STAlTzItA from=from@from all done -_ queue.go:308 Queue.SendLoop a49jtPw81OY: from from@from -_ queue.go:367 Queue.SendLoop a49jtPw81OY: testuser@localhost sent -2024-10-25 16:45:05.567717 a49jtPw81OY from=from@from to=testuser@localhost sent -_ conn.go:722 SMTP.Conn 127.0.0.1:34102: Queued from from@from to [to@localhost] - a49jtPw81OY -2024-10-25 16:45:05.567763 a49jtPw81OY from=from@from queued ip=127.0.0.1:34102 to=[to@localhost] -_ queue.go:342 Queue.SendLoop a49jtPw81OY: all done -2024-10-25 16:45:05.615988 a49jtPw81OY from=from@from all done -_ conn.go:318 SMTP.Conn 127.0.0.1:34102: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:722 SMTP.Conn 127.0.0.1:34102: Queued from from@from to [to@localhost] - V2GuF72uYmw -2024-10-25 16:45:05.924512 V2GuF72uYmw from=from@from queued ip=127.0.0.1:34102 to=[to@localhost] -_ queue.go:308 Queue.SendLoop V2GuF72uYmw: from from@from -_ queue.go:367 Queue.SendLoop V2GuF72uYmw: testuser@localhost sent -2024-10-25 16:45:05.924759 V2GuF72uYmw from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.74s) +_ conn.go:722 SMTP.Conn 127.0.0.1:57866: Queued from from@from to [to@localhost] - rxxyQD2jiuo +2025-11-27 23:10:17.271728 rxxyQD2jiuo from=from@from queued ip=127.0.0.1:57866 to=[to@localhost] +_ queue.go:308 Queue.SendLoop rxxyQD2jiuo: from from@from +_ queue.go:367 Queue.SendLoop rxxyQD2jiuo: testuser@localhost sent +2025-11-27 23:10:17.271875 rxxyQD2jiuo from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop rxxyQD2jiuo: all done +2025-11-27 23:10:17.284818 rxxyQD2jiuo from=from@from all done +_ conn.go:722 SMTP.Conn 127.0.0.1:57866: Queued from from@from to [to@localhost] - f4cFVsy5Xws +2025-11-27 23:10:17.368062 f4cFVsy5Xws from=from@from queued ip=127.0.0.1:57866 to=[to@localhost] +_ queue.go:308 Queue.SendLoop f4cFVsy5Xws: from from@from +_ queue.go:367 Queue.SendLoop f4cFVsy5Xws: testuser@localhost sent +2025-11-27 23:10:17.368266 f4cFVsy5Xws from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop f4cFVsy5Xws: all done +2025-11-27 23:10:17.380724 f4cFVsy5Xws from=from@from all done +_ conn.go:318 SMTP.Conn 127.0.0.1:57866: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:722 SMTP.Conn 127.0.0.1:57866: Queued from from@from to [to@localhost] - 1L6rP02oy1k +2025-11-27 23:10:17.603105 1L6rP02oy1k from=from@from queued ip=127.0.0.1:57866 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 1L6rP02oy1k: from from@from +_ queue.go:367 Queue.SendLoop 1L6rP02oy1k: testuser@localhost sent +2025-11-27 23:10:17.603259 1L6rP02oy1k from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.43s) === RUN TestSimpleCommands -_ conn.go:318 SMTP.Conn 127.0.0.1:34118: 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:34118: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:318 SMTP.Conn 127.0.0.1:57874: 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:57874: 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:34126: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:348 SMTP.Conn 127.0.0.1:34126: error: exiting with error: line too long +_ conn.go:318 SMTP.Conn 127.0.0.1:57886: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:348 SMTP.Conn 127.0.0.1:57886: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.00s) === RUN TestRepeatedStartTLS -_ conn.go:318 SMTP.Conn 127.0.0.1:34134: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:318 SMTP.Conn 127.0.0.1:57904: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestRepeatedStartTLS (0.00s) === RUN TestStartTLSOnTLS -_ conn.go:318 SMTP.Conn 127.0.0.1:44604: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:318 SMTP.Conn 127.0.0.1:55060: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestStartTLSOnTLS (0.00s) === RUN TestAddDKIMSigner - testlib.go:32: test directory: "/tmp/testlib_3833719584" ---- PASS: TestAddDKIMSigner (0.01s) + testlib.go:32: test directory: "/tmp/testlib_2928344820" +--- PASS: TestAddDKIMSigner (0.00s) === RUN FuzzConnection --- PASS: FuzzConnection (0.00s) PASS -_ queue.go:375 Queue.SendLoop V2GuF72uYmw: error: failed to write: rename /tmp/chasquid_test:1494050706/queue/.m:V2GuF72uYmw4141407513 /tmp/chasquid_test:1494050706/queue/m:V2GuF72uYmw: no such file or directory -_ queue.go:342 Queue.SendLoop V2GuF72uYmw: all done -2024-10-25 16:45:05.956667 V2GuF72uYmw from=from@from all done -E queue.go:230 failed to remove queue file "/tmp/chasquid_test:1494050706/queue/m:V2GuF72uYmw": remove /tmp/chasquid_test:1494050706/queue/m:V2GuF72uYmw: no such file or directory -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.687s +_ queue.go:375 Queue.SendLoop 1L6rP02oy1k: error: failed to write: rename /tmp/chasquid_test:707537940/queue/.m:1L6rP02oy1k1794287127 /tmp/chasquid_test:707537940/queue/m:1L6rP02oy1k: no such file or directory +_ queue.go:342 Queue.SendLoop 1L6rP02oy1k: all done +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.216s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -2338,31 +2372,31 @@ --- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.02s) +--- PASS: TestPolicyTooBig (0.01s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_2621152577" + testlib.go:32: test directory: "/tmp/testlib_928830557" sts_test.go:277: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:286: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:300: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:309: cache fetched domErr: ---- PASS: TestCacheBasics (0.01s) +--- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_1122091950" + testlib.go:32: test directory: "/tmp/testlib_2035866283" sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} ---- PASS: TestCacheBadData (0.01s) +--- PASS: TestCacheBadData (0.00s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_3294151430" + testlib.go:32: test directory: "/tmp/testlib_3901322636" 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_3392659081" + testlib.go:32: test directory: "/tmp/testlib_3541694530" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -2372,9 +2406,9 @@ === RUN TestHTTPGet --- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.073s +ok blitiri.com.ar/go/chasquid/internal/sts 0.033s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_4133641271" + testlib.go:32: test directory: "/tmp/testlib_2449549048" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -2385,54 +2419,54 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_844980965" + testlib.go:32: test directory: "/tmp/testlib_1823103141" --- 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_2771611386" + testlib.go:32: test directory: "/tmp/testlib_1096833987" --- PASS: TestGenerateCert (0.03s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.04s) +--- PASS: TestGenerateCertBadDir (0.02s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.115s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.076s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.015s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.003s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test1542276188" - userdb_test.go:37: file: "/tmp/userdb_test886208189" + userdb_test.go:37: file: "/tmp/userdb_test2898046512" + userdb_test.go:37: file: "/tmp/userdb_test1987355082" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test1517663218" ---- PASS: TestWrite (0.73s) + userdb_test.go:37: file: "/tmp/userdb_test485134316" +--- PASS: TestWrite (0.42s) === RUN TestNew ---- PASS: TestNew (0.12s) +--- PASS: TestNew (0.05s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test120792647" + userdb_test.go:37: file: "/tmp/userdb_test3621420626" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test151962984" + userdb_test.go:37: file: "/tmp/userdb_test2696847874" --- PASS: TestPlainScheme (0.00s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test2961672578" + userdb_test.go:37: file: "/tmp/userdb_test2273943893" --- PASS: TestDeniedScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test495546018" ---- PASS: TestReload (0.02s) + userdb_test.go:37: file: "/tmp/userdb_test3399120472" +--- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test1046598115" ---- PASS: TestRemoveUser (0.10s) + userdb_test.go:37: file: "/tmp/userdb_test1681325368" +--- PASS: TestRemoveUser (0.05s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test1375080208" ---- PASS: TestExists (0.09s) + userdb_test.go:37: file: "/tmp/userdb_test512208340" +--- PASS: TestExists (0.05s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.083s +ok blitiri.com.ar/go/chasquid/internal/userdb 0.584s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -2486,8 +2520,8 @@ dpkg-gencontrol: warning: package chasquid: substitution variable ${misc:Static-Built-Using} unused, but is defined dh_md5sums -O--buildsystem=golang dh_builddeb -O--buildsystem=golang -dpkg-deb: building package 'chasquid-dbgsym' in '../chasquid-dbgsym_1.14.0-2_amd64.deb'. dpkg-deb: building package 'chasquid' in '../chasquid_1.14.0-2_amd64.deb'. +dpkg-deb: building package 'chasquid-dbgsym' in '../chasquid-dbgsym_1.14.0-2_amd64.deb'. dpkg-genbuildinfo --build=binary -O../chasquid_1.14.0-2_amd64.buildinfo dpkg-genchanges --build=binary -O../chasquid_1.14.0-2_amd64.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -2495,12 +2529,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/292610/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/292610/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/3270454 and its subdirectories -I: Current time: Fri Oct 25 04:45:39 -12 2024 -I: pbuilder-time-stamp: 1729874739 +I: removing directory /srv/workspace/pbuilder/292610 and its subdirectories +I: Current time: Fri Nov 28 13:10:34 +14 2025 +I: pbuilder-time-stamp: 1764285034