Diff of the two buildlogs: -- --- b1/build.log 2024-05-13 15:10:30.217215449 +0000 +++ b2/build.log 2024-05-13 15:11:51.505755168 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Jun 15 09:30:33 -12 2025 -I: pbuilder-time-stamp: 1750023033 +I: Current time: Tue May 14 05:10:33 +14 2024 +I: pbuilder-time-stamp: 1715613033 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/1655212/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/D01_modify_environment starting +debug: Running on codethink04-arm64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 May 13 15:10 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/3978512/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='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=12 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='fb07a85b1f2d427f985e98884051c1c8' - 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='1655212' - PS1='# ' - PS2='> ' + INVOCATION_ID=b08eb90974c84300a0a1e34c9724f720 + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-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=3978512 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.pPhL1s1z/pbuilderrc_DTI5 --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.pPhL1s1z/b1 --logfile b1/build.log chasquid_1.13-1.dsc' - SUDO_GID='109' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.4: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.pPhL1s1z/pbuilderrc_GNuw --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.pPhL1s1z/b2 --logfile b2/build.log chasquid_1.13-1.dsc' + SUDO_GID=109 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://192.168.101.4:3128 I: uname -a - Linux codethink03-arm64 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jun 15 14:06 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1655212/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 13 07:43 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -176,7 +208,7 @@ Get: 44 http://deb.debian.org/debian unstable/main arm64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 45 http://deb.debian.org/debian unstable/main arm64 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 46 http://deb.debian.org/debian unstable/main arm64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 66.7 MB in 1s (102 MB/s) +Fetched 66.7 MB in 0s (204 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 ... 19744 files and directories currently installed.) @@ -375,7 +407,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/chasquid-1.13/ && 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.13-1_source.changes +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/chasquid-1.13/ && 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.13-1_source.changes dpkg-buildpackage: info: source package chasquid dpkg-buildpackage: info: source version 1.13-1 dpkg-buildpackage: info: source distribution unstable @@ -396,103 +432,103 @@ make[1]: Entering directory '/build/reproducible-path/chasquid-1.13' dh_auto_build -- -ldflags " -X main.version=1.13-1 -X main.sourceDateTs=1703600557" cd obj-aarch64-linux-gnu && go install -trimpath -v -p 12 -ldflags " -X main.version=1.13-1 -X main.sourceDateTs=1703600557" blitiri.com.ar/go/chasquid blitiri.com.ar/go/chasquid/cmd/chasquid-util blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/courier blitiri.com.ar/go/chasquid/internal/domaininfo blitiri.com.ar/go/chasquid/internal/dovecot blitiri.com.ar/go/chasquid/internal/envelope blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/chasquid/internal/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/itoa internal/goarch -internal/godebugs +runtime/internal/syscall +internal/unsafeheader +encoding internal/coverage/rtcov -internal/goexperiment +internal/godebugs +internal/cpu internal/goos -encoding -internal/unsafeheader -unicode/utf8 -math/bits -internal/race +internal/goexperiment sync/atomic -internal/cpu internal/abi -runtime/internal/syscall internal/chacha8rand -internal/itoa runtime/internal/math -runtime/internal/sys unicode +unicode/utf8 +internal/race +math/bits +runtime/internal/sys cmp log/internal internal/nettrace google.golang.org/protobuf/internal/flags -unicode/utf16 -math -slices -google.golang.org/protobuf/internal/set container/ring +unicode/utf16 container/list crypto/internal/alias crypto/subtle -crypto/internal/boring/sig -vendor/golang.org/x/crypto/cryptobyte/asn1 -vendor/golang.org/x/crypto/internal/alias +math internal/bytealg +slices runtime/internal/atomic +google.golang.org/protobuf/internal/set +vendor/golang.org/x/crypto/cryptobyte/asn1 +vendor/golang.org/x/crypto/internal/alias +crypto/internal/boring/sig blitiri.com.ar/go/chasquid/internal/set runtime -sync internal/reflectlite +sync internal/testlog +google.golang.org/protobuf/internal/pragma internal/bisect internal/singleflight -google.golang.org/protobuf/internal/pragma runtime/cgo internal/godebug -internal/intern -math/rand errors +internal/intern sort -strconv -internal/oserror +math/rand +golang.org/x/text/internal/tag internal/safefilepath -path -io vendor/golang.org/x/net/dns/dnsmessage -strings -hash -bytes -crypto/internal/randutil -golang.org/x/text/internal/tag +internal/oserror +strconv +io +path crypto/internal/nistec/fiat syscall -reflect -net/netip -hash/fnv +hash text/tabwriter +strings +bytes +crypto/internal/randutil hash/crc32 -crypto +hash/fnv +reflect crypto/rc4 -vendor/golang.org/x/text/transform -bufio +crypto +net/netip html -golang.org/x/text/transform -net/http/internal/ascii regexp/syntax +net/http/internal/ascii +bufio +vendor/golang.org/x/text/transform +golang.org/x/text/transform golang.org/x/text/runes golang.org/x/text/width regexp +internal/syscall/execenv internal/syscall/unix time -internal/syscall/execenv internal/poll io/fs context -internal/fmtsort -encoding/binary embed google.golang.org/protobuf/internal/editiondefaults +internal/fmtsort +encoding/binary os encoding/base64 -golang.org/x/sys/unix crypto/cipher -crypto/internal/edwards25519/field -crypto/internal/nistec -crypto/md5 vendor/golang.org/x/crypto/internal/poly1305 +crypto/md5 +crypto/internal/nistec +crypto/internal/edwards25519/field +golang.org/x/sys/unix encoding/pem crypto/internal/boring crypto/des @@ -506,84 +542,84 @@ vendor/golang.org/x/crypto/hkdf golang.org/x/crypto/pbkdf2 vendor/golang.org/x/crypto/chacha20poly1305 -golang.org/x/crypto/scrypt -crypto/ecdh +fmt path/filepath google.golang.org/protobuf/internal/detrand -internal/lazyregexp -fmt io/ioutil +blitiri.com.ar/go/chasquid/internal/safeio +internal/lazyregexp os/signal internal/sysinfo -blitiri.com.ar/go/chasquid/internal/safeio -net +golang.org/x/crypto/scrypt +crypto/ecdh os/exec +net flag log -go/token -compress/flate encoding/json +google.golang.org/protobuf/internal/errors +math/big google.golang.org/protobuf/internal/version +go/token net/url text/template/parse -google.golang.org/protobuf/internal/errors -math/big +compress/flate encoding/hex google.golang.org/protobuf/encoding/protowire -vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/net/http2/hpack vendor/golang.org/x/text/unicode/bidi +vendor/golang.org/x/text/unicode/norm google.golang.org/protobuf/reflect/protoreflect +vendor/golang.org/x/net/http2/hpack mime mime/quotedprintable +compress/gzip net/http/internal blitiri.com.ar/go/chasquid/internal/envelope -compress/gzip golang.org/x/text/unicode/bidi golang.org/x/text/unicode/norm golang.org/x/text/internal/language -vendor/golang.org/x/text/secure/bidirule blitiri.com.ar/go/chasquid/internal/tlsconst +text/template +vendor/golang.org/x/text/secure/bidirule internal/profile runtime/pprof +golang.org/x/text/secure/bidirule google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order -google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/reflect/protoregistry +google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts -text/template vendor/golang.org/x/net/idna -golang.org/x/text/secure/bidirule runtime/trace -google.golang.org/protobuf/proto -runtime/debug -golang.org/x/term -google.golang.org/protobuf/internal/encoding/defval -golang.org/x/text/internal/language/compact -golang.org/x/net/idna -testing -golang.org/x/text/language -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/filedesc crypto/rand crypto/elliptic crypto/internal/bigmod +html/template crypto/internal/boring/bbig encoding/asn1 -crypto/dsa crypto/ed25519 -html/template -golang.org/x/text/internal -blitiri.com.ar/go/chasquid/internal/protoio +google.golang.org/protobuf/proto +crypto/dsa +google.golang.org/protobuf/internal/encoding/defval +golang.org/x/net/idna +golang.org/x/text/internal/language/compact +runtime/debug +golang.org/x/term crypto/rsa -golang.org/x/text/cases +golang.org/x/text/language vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix +testing +google.golang.org/protobuf/encoding/prototext +google.golang.org/protobuf/internal/filedesc crypto/ecdsa +golang.org/x/text/internal +golang.org/x/text/cases +blitiri.com.ar/go/chasquid/internal/protoio golang.org/x/text/secure/precis google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/internal/impl @@ -591,20 +627,20 @@ google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl log/syslog -net/textproto vendor/golang.org/x/net/http/httpproxy blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/spf -crypto/x509 +net/textproto blitiri.com.ar/go/systemd -blitiri.com.ar/go/chasquid/internal/userdb +crypto/x509 blitiri.com.ar/go/chasquid/internal/protoio/testpb +blitiri.com.ar/go/chasquid/internal/userdb blitiri.com.ar/go/log vendor/golang.org/x/net/http/httpguts mime/multipart +blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/dovecot net/mail -blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/config crypto/tls net/http/httptrace @@ -612,18 +648,18 @@ blitiri.com.ar/go/chasquid/internal/testlib net/http blitiri.com.ar/go/chasquid/internal/smtp -blitiri.com.ar/go/chasquid/internal/nettrace expvar golang.org/x/net/context/ctxhttp +blitiri.com.ar/go/chasquid/internal/nettrace net/http/pprof 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/auth blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/aliases +blitiri.com.ar/go/chasquid/internal/sts +blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/cmd/chasquid-util blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/courier @@ -666,7 +702,7 @@ _ aliases.go:547 Hook.Alias-Resolve a@localA: error: exit status 1 _ aliases.go:547 Hook.Alias-Resolve *@localA: error: exit status 1 _ aliases.go:547 Hook.Alias-Resolve a@localA: error: exit status 1 ---- PASS: TestHookError (0.06s) +--- PASS: TestHookError (0.01s) === RUN FuzzReader === RUN FuzzReader/seed#0 === RUN FuzzReader/1c24d2215db69748c6fd16797673ad11ebc7e6167fe1bc1f54c6959ec10407b6 @@ -682,7 +718,7 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.124s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.033s === 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 @@ -695,11 +731,11 @@ auth_test.go:56: OK: "\xffa@b\x00\xffa@b\x00pass" failed with bidirule: failed Bidi Rule --- PASS: TestDecodeResponse (0.00s) === RUN TestAuthenticate ---- PASS: TestAuthenticate (0.70s) +--- PASS: TestAuthenticate (0.41s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.20s) +--- PASS: TestMultipleBackends (0.18s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload @@ -728,46 +764,47 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.978s +ok blitiri.com.ar/go/chasquid/internal/auth 0.609s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_1601276342" + testlib.go:32: test directory: "/tmp/testlib_3154406923" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_184955781" + testlib.go:32: test directory: "/tmp/testlib_2301712928" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_2728094566" + testlib.go:32: test directory: "/tmp/testlib_1300519824" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_3608602700" + testlib.go:32: test directory: "/tmp/testlib_1667438187" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.073s +ok blitiri.com.ar/go/chasquid/internal/config 0.021s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] +? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_1522479259" ---- PASS: TestMDA (0.03s) + testlib.go:32: test directory: "/tmp/testlib_1556263079" +--- PASS: TestMDA (0.00s) === RUN TestMDATimeout _ mda.go:73 Courier.MDA to@local: error: operation timed out ---- PASS: TestMDATimeout (0.11s) +--- 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_4190986064" - testlib.go:32: test directory: "/tmp/testlib_1768730017" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::46411: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::46411: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_1877434487" + testlib.go:32: test directory: "/tmp/testlib_3698615107" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::41979: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::41979: too many colons in address fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -775,26 +812,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.08s) +--- PASS: TestSMTP (0.04s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_2914925369" - testlib.go:32: test directory: "/tmp/testlib_3526266265" + testlib.go:32: test directory: "/tmp/testlib_4116753706" + testlib.go:32: test directory: "/tmp/testlib_1068790561" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:56176->127.0.0.1:39079: 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:56176->127.0.0.1:39079: 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:56176->127.0.0.1:39079: i/o timeout) - smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:56176->127.0.0.1:39079: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:34566->127.0.0.1:33893: 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:34566->127.0.0.1:33893: 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:34566->127.0.0.1:33893: i/o timeout) + smtp_test.go:140: failed as expected: all MXs returned transient failures (last: Error saying hello: write tcp 127.0.0.1:34566->127.0.0.1:33893: i/o timeout) fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_1491249282" - testlib.go:32: test directory: "/tmp/testlib_2162837253" + testlib.go:32: test directory: "/tmp/testlib_1031156425" + testlib.go:32: test directory: "/tmp/testlib_872479025" 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_2274574354" - testlib.go:32: test directory: "/tmp/testlib_1943807602" + testlib.go:32: test directory: "/tmp/testlib_967375331" + testlib.go:32: test directory: "/tmp/testlib_3465097643" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -802,8 +839,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_2443461323" - testlib.go:32: test directory: "/tmp/testlib_2034764729" + testlib.go:32: test directory: "/tmp/testlib_4048134722" + testlib.go:32: test directory: "/tmp/testlib_1331482171" 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: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_2546864881" - testlib.go:32: test directory: "/tmp/testlib_2390493583" + testlib.go:32: test directory: "/tmp/testlib_2255792569" + testlib.go:32: test directory: "/tmp/testlib_3971218605" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -822,9 +859,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.69s) +--- PASS: TestSMTPErrors (1.29s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_2557525069" + testlib.go:32: test directory: "/tmp/testlib_915630128" _ smtp.go:82 Courier.SMTP to@to: error: Could not find mail server: smtp_test.go:158: got permanent failure, as expected: Could not find mail server: --- PASS: TestNoMXServer (0.00s) @@ -839,8 +876,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_2352121441" - testlib.go:32: test directory: "/tmp/testlib_1973985550" + testlib.go:32: test directory: "/tmp/testlib_2525218521" + testlib.go:32: test directory: "/tmp/testlib_2246305869" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -851,7 +888,7 @@ 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_2198067715" + testlib.go:32: test directory: "/tmp/testlib_2625732964" 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 @@ -859,26 +896,26 @@ _ 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.25s) +--- PASS: TestTLS (0.11s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_1806140862" - testlib.go:32: test directory: "/tmp/testlib_1887500479" + testlib.go:32: test directory: "/tmp/testlib_1932460273" + testlib.go:32: test directory: "/tmp/testlib_3271204115" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" _ smtp.go:171 Courier.SMTP to@to: error: TLS error, retrying without TLS: 500 starttls err - fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:105: fakeServer exiting: EOF + fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" fakeserver_test.go:109: fakeServer read: "RCPT TO:" fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestTLSError (0.09s) +--- PASS: TestTLSError (0.06s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_3769796022" - testlib.go:32: test directory: "/tmp/testlib_2382173461" + testlib.go:32: test directory: "/tmp/testlib_205365711" + testlib.go:32: test directory: "/tmp/testlib_2533622354" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -886,7 +923,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_1386938551" + testlib.go:32: test directory: "/tmp/testlib_1018075983" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -897,11 +934,11 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestSTSPolicyEnforcement (0.21s) +--- PASS: TestSTSPolicyEnforcement (0.13s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.510s +ok blitiri.com.ar/go/chasquid/internal/courier 1.768s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_2067703820" + testlib.go:32: test directory: "/tmp/testlib_224097464" _ 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 @@ -910,14 +947,14 @@ _ domaininfo.go:182 DomainInfo.SetToPlain d1: set to plain --- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_3235228357" + testlib.go:32: test directory: "/tmp/testlib_530880694" _ domaininfo.go:153 DomainInfo.Outgoing insecure: insecure outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:117 DomainInfo.Incoming insecure: insecure incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:153 DomainInfo.Outgoing secure: secure outgoing level raised: TLS_SECURE > PLAIN _ domaininfo.go:117 DomainInfo.Incoming secure: secure incoming level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_1678718018" + testlib.go:32: test directory: "/tmp/testlib_2712146674" _ 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 @@ -932,27 +969,27 @@ _ domaininfo.go:145 DomainInfo.Outgoing ssip: error: ssip outgoing denied: PLAIN < TLS_SECURE --- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_1321546825" + testlib.go:32: test directory: "/tmp/testlib_856506553" _ domaininfo.go:117 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto: syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto: syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents --- PASS: TestErrors (0.00s) === RUN TestDirectoryErrors - testlib.go:32: test directory: "/tmp/testlib_3162125781" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_3162125781/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_3162125781/db/.s:d171964654: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_196562079" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_196562079/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_196562079/db/.s:d13134625706: no such file or directory --- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.070s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.021s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_3225524244" + testlib.go:32: test directory: "/tmp/testlib_651920991" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.052s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.018s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -960,13 +997,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.036s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.008s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.065s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.013s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -991,21 +1028,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.053s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.008s === RUN TestBadServer - asm_arm64.s:1222: FakeServer &{{0x4000200080}}: accepted - asm_arm64.s:1222: FakeServer &{{0x4000200080}}: readRequest: "" "" / EOF - asm_arm64.s:1222: FakeServer &{{0x4000200080}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-3471633592/rpc.sock->@: write: broken pipe - asm_arm64.s:1222: FakeServer &{{0x4000200080}}: closing - asm_arm64.s:1222: FakeServer &{{0x4000200200}}: accepted - asm_arm64.s:1222: FakeServer &{{0x4000200200}}: readRequest: "Echo" "" / - asm_arm64.s:1222: FakeServer &{{0x4000200200}}: writeMessage("xxx"): 3 - asm_arm64.s:1222: FakeServer &{{0x4000200200}}: closing ---- PASS: TestBadServer (0.03s) + asm_arm64.s:1222: FakeServer &{{0x4000114480}}: accepted + asm_arm64.s:1222: FakeServer &{{0x4000114480}}: readRequest: "" "" / EOF + asm_arm64.s:1222: FakeServer &{{0x4000114480}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-2724936260/rpc.sock->@: write: broken pipe + asm_arm64.s:1222: FakeServer &{{0x4000114480}}: closing + asm_arm64.s:1222: FakeServer &{{0x4000114600}}: accepted + asm_arm64.s:1222: FakeServer &{{0x4000114600}}: readRequest: "Echo" "" / + asm_arm64.s:1222: FakeServer &{{0x4000114600}}: writeMessage("xxx"): 3 + asm_arm64.s:1222: FakeServer &{{0x4000114600}}: closing +--- PASS: TestBadServer (0.01s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-1995758134/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-881253962/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1013,7 +1050,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-1995758134/sock: error: Accept error: accept unix /tmp/rpc-test-1995758134/sock: use of closed network connection +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-881253962/sock: error: Accept error: accept unix /tmp/rpc-test-881253962/sock: use of closed network connection --- PASS: TestEndToEnd (0.01s) --- PASS: TestEndToEnd/Echo (0.00s) --- PASS: TestEndToEnd/Echo#01 (0.00s) @@ -1028,7 +1065,7 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.084s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.040s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -1036,7 +1073,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.056s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.015s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1044,7 +1081,7 @@ === RUN TestHistogramEmpty --- PASS: TestHistogramEmpty (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.01s) +--- PASS: TestHTTP (0.02s) === RUN TestHTTPLong --- PASS: TestHTTPLong (0.00s) === RUN TestHTTPErrors @@ -1052,7 +1089,7 @@ === RUN TestHTTPUroboro --- PASS: TestHTTPUroboro (0.01s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.02s) +--- PASS: TestHTTPDeep (0.00s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler @@ -1072,7 +1109,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.148s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.091s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1132,40 +1169,40 @@ --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.045s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.011s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_1940359224" + testlib.go:32: test directory: "/tmp/testlib_1880612498" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_2746771090" + testlib.go:32: test directory: "/tmp/testlib_883502381" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_1756042937" + testlib.go:32: test directory: "/tmp/testlib_3100256754" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_3432358483" ---- PASS: TestFileErrors (0.01s) + testlib.go:32: test directory: "/tmp/testlib_3304565515" +--- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_2379002855" + testlib.go:32: test directory: "/tmp/testlib_238608116" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.068s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.021s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Sun, 15 Jun 2025 21:32:31 +0000 + Message-ID: + Date: Mon, 13 May 2024 15:11:28 +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="2_e2kJxHue8" + boundary="ATq9JGtpJ7Q" - --2_e2kJxHue8 + --ATq9JGtpJ7Q Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1190,7 +1227,7 @@ oh! the humanity! - --2_e2kJxHue8 + --ATq9JGtpJ7Q Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1221,7 +1258,7 @@ - --2_e2kJxHue8 + --ATq9JGtpJ7Q Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1231,73 +1268,73 @@ Data ñaca. - --2_e2kJxHue8-- + --ATq9JGtpJ7Q-- ---- PASS: TestDSN (0.01s) +--- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_4290199729" -_ queue.go:308 Queue.SendLoop 3Fz2tFp0y-U: from from -_ queue.go:367 Queue.SendLoop 3Fz2tFp0y-U: nodomain sent -_ queue.go:367 Queue.SendLoop 3Fz2tFp0y-U: x@remote sent -_ queue.go:367 Queue.SendLoop 3Fz2tFp0y-U: am@loco sent -_ queue.go:342 Queue.SendLoop 3Fz2tFp0y-U: all done ---- PASS: TestBasic (0.02s) + testlib.go:32: test directory: "/tmp/testlib_3239889016" +_ queue.go:308 Queue.SendLoop eFKlG73aVIw: from from +_ queue.go:367 Queue.SendLoop eFKlG73aVIw: nodomain sent +_ queue.go:367 Queue.SendLoop eFKlG73aVIw: am@loco sent +_ queue.go:367 Queue.SendLoop eFKlG73aVIw: x@remote sent +_ queue.go:342 Queue.SendLoop eFKlG73aVIw: all done +--- PASS: TestBasic (0.00s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_665265333" -_ queue.go:308 Queue.SendLoop lSCwstOMxvo: from from@loco -_ queue.go:465 Queue.SendLoop lSCwstOMxvo: queued DSN: xEeHB7tNo0E -_ queue.go:342 Queue.SendLoop lSCwstOMxvo: all done -_ queue.go:308 Queue.SendLoop xEeHB7tNo0E: from <> -_ queue.go:367 Queue.SendLoop xEeHB7tNo0E: from@loco sent -_ queue.go:342 Queue.SendLoop xEeHB7tNo0E: all done + testlib.go:32: test directory: "/tmp/testlib_1537292321" +_ queue.go:308 Queue.SendLoop nfKwM_yJ-N4: from from@loco +_ queue.go:465 Queue.SendLoop nfKwM_yJ-N4: queued DSN: pA1ukCTsS2Q +_ queue.go:342 Queue.SendLoop nfKwM_yJ-N4: all done +_ queue.go:308 Queue.SendLoop pA1ukCTsS2Q: from <> +_ queue.go:367 Queue.SendLoop pA1ukCTsS2Q: from@loco sent +_ queue.go:342 Queue.SendLoop pA1ukCTsS2Q: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_1492836050" -_ queue.go:308 Queue.SendLoop IC5sWV28wDI: from from -_ queue.go:367 Queue.SendLoop IC5sWV28wDI: ata@hualpa sent -_ queue.go:367 Queue.SendLoop IC5sWV28wDI: pq@loco sent -_ queue.go:367 Queue.SendLoop IC5sWV28wDI: rs@loco sent -_ queue.go:342 Queue.SendLoop IC5sWV28wDI: all done + testlib.go:32: test directory: "/tmp/testlib_195259466" +_ queue.go:308 Queue.SendLoop wtckni_P-9w: from from +_ queue.go:367 Queue.SendLoop wtckni_P-9w: ata@hualpa sent +_ queue.go:367 Queue.SendLoop wtckni_P-9w: pq@loco sent +_ queue.go:367 Queue.SendLoop wtckni_P-9w: rs@loco sent +_ queue.go:342 Queue.SendLoop wtckni_P-9w: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_880710775" + testlib.go:32: test directory: "/tmp/testlib_501341812" _ queue.go:164 Queue.Put from: error: queue full --- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_2176850533" -_ queue.go:308 Queue.SendLoop SU8JyX6iuQ8: from from -_ queue.go:367 Queue.SendLoop SU8JyX6iuQ8: to sent -_ queue.go:375 Queue.SendLoop SU8JyX6iuQ8: error: failed to write: open /tmp/testlib_880710775/.m:SU8JyX6iuQ8808995596: no such file or directory -_ queue.go:342 Queue.SendLoop SU8JyX6iuQ8: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_880710775/m:SU8JyX6iuQ8": remove /tmp/testlib_880710775/m:SU8JyX6iuQ8: no such file or directory ---- PASS: TestPipes (0.01s) + testlib.go:32: test directory: "/tmp/testlib_552930839" +_ queue.go:308 Queue.SendLoop aqfRxqhaPYg: from from +_ queue.go:367 Queue.SendLoop aqfRxqhaPYg: to sent +_ queue.go:375 Queue.SendLoop aqfRxqhaPYg: error: failed to write: open /tmp/testlib_501341812/.m:aqfRxqhaPYg849113036: no such file or directory +_ queue.go:342 Queue.SendLoop aqfRxqhaPYg: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_501341812/m:aqfRxqhaPYg": remove /tmp/testlib_501341812/m:aqfRxqhaPYg: 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_892674626" -_ queue.go:308 Queue.SendLoop WpGDuf9Q7ZU: from from@loco -_ queue.go:367 Queue.SendLoop WpGDuf9Q7ZU: to@to sent -_ queue.go:342 Queue.SendLoop WpGDuf9Q7ZU: all done + testlib.go:32: test directory: "/tmp/testlib_2736276083" +_ queue.go:308 Queue.SendLoop 43WKWVFz8qQ: from from@loco +_ queue.go:367 Queue.SendLoop 43WKWVFz8qQ: to@to sent +_ queue.go:342 Queue.SendLoop 43WKWVFz8qQ: all done --- PASS: TestSerialization (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.128s +ok blitiri.com.ar/go/chasquid/internal/queue 0.025s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_1931525556" + testlib.go:32: test directory: "/tmp/testlib_3477474736" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_1102400581" + testlib.go:32: test directory: "/tmp/testlib_3100040991" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_16689906" + testlib.go:32: test directory: "/tmp/testlib_3986862899" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.032s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.020s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.040s +ok blitiri.com.ar/go/chasquid/internal/set 0.013s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1311,17 +1348,17 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.20s) +--- PASS: TestLineTooLong (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.266s -? blitiri.com.ar/go/chasquid/internal/trace [no test files] -_ server.go:242 Server listening on 127.0.0.1:35741 (SMTP) -2025-06-15 21:32:33.083853 daemon listening on 127.0.0.1:35741 -_ server.go:242 Server listening on 127.0.0.1:41725 (submission) -2025-06-15 21:32:33.083921 daemon listening on 127.0.0.1:41725 -_ server.go:242 Server listening on 127.0.0.1:37241 (submission+TLS) -2025-06-15 21:32:33.083938 daemon listening on 127.0.0.1:37241 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.028s +_ server.go:242 Server listening on 127.0.0.1:43019 (SMTP) +2024-05-13 15:11:29.214798 daemon listening on 127.0.0.1:43019 +_ server.go:242 Server listening on 127.0.0.1:37031 (submission) +2024-05-13 15:11:29.214879 daemon listening on 127.0.0.1:37031 +_ server.go:242 Server listening on 127.0.0.1:46415 (submission+TLS) +2024-05-13 15:11:29.214900 daemon listening on 127.0.0.1:46415 === RUN TestSecLevel +_ conn.go:188 SMTP.Conn 127.0.0.1:53934: error: error completing TLS handshake: EOF _ domaininfo.go:117 DomainInfo.Incoming slc: slc incoming level raised: TLS_CLIENT > PLAIN _ domaininfo.go:109 DomainInfo.Incoming slc: error: slc incoming denied: PLAIN < TLS_CLIENT _ conn.go:538 testconn testconn: error: security level check for slc failed (PLAIN) @@ -1337,238 +1374,238 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:188 SMTP.Conn 127.0.0.1:54280: error: error completing TLS handshake: EOF -_ conn.go:689 SMTP.Conn 127.0.0.1:38460: Queued from from@from to [to@localhost] - L4UtaXeNmrM -2025-06-15 21:32:33.195153 L4UtaXeNmrM from=from@from queued ip=127.0.0.1:38460 to=[to@localhost] -_ queue.go:308 Queue.SendLoop L4UtaXeNmrM: from from@from -_ queue.go:367 Queue.SendLoop L4UtaXeNmrM: testuser@localhost sent -2025-06-15 21:32:33.195246 L4UtaXeNmrM from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop L4UtaXeNmrM: all done -2025-06-15 21:32:33.195445 L4UtaXeNmrM from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50352: Queued from from@from to [to@localhost] - iSHrCBuY-1k +2024-05-13 15:11:29.319972 iSHrCBuY-1k from=from@from queued ip=127.0.0.1:50352 to=[to@localhost] +_ queue.go:308 Queue.SendLoop iSHrCBuY-1k: from from@from +_ queue.go:367 Queue.SendLoop iSHrCBuY-1k: testuser@localhost sent +2024-05-13 15:11:29.320249 iSHrCBuY-1k from=from@from to=testuser@localhost sent --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ conn.go:689 SMTP.Conn 127.0.0.1:38468: Queued from from@from to [to@localhost] - ypWW14uf2Hk -2025-06-15 21:32:33.197925 ypWW14uf2Hk from=from@from queued ip=127.0.0.1:38468 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ypWW14uf2Hk: from from@from -_ queue.go:367 Queue.SendLoop ypWW14uf2Hk: testuser@localhost sent -2025-06-15 21:32:33.197987 ypWW14uf2Hk from=from@from to=testuser@localhost sent ---- PASS: TestSimpleTLS (0.00s) +_ queue.go:342 Queue.SendLoop iSHrCBuY-1k: all done +2024-05-13 15:11:29.320649 iSHrCBuY-1k from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50356: Queued from from@from to [to@localhost] - jF3LC4FU1p0 +2024-05-13 15:11:29.326698 jF3LC4FU1p0 from=from@from queued ip=127.0.0.1:50356 to=[to@localhost] +_ queue.go:308 Queue.SendLoop jF3LC4FU1p0: from from@from +_ queue.go:367 Queue.SendLoop jF3LC4FU1p0: testuser@localhost sent +2024-05-13 15:11:29.326912 jF3LC4FU1p0 from=from@from to=testuser@localhost sent +--- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ conn.go:689 SMTP.Conn 127.0.0.1:38480: Queued from from@from to [to@localhost] - Hh7CK9pc3aI -2025-06-15 21:32:33.200673 Hh7CK9pc3aI from=from@from queued ip=127.0.0.1:38480 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Hh7CK9pc3aI: from from@from -_ queue.go:367 Queue.SendLoop Hh7CK9pc3aI: testuser@localhost sent -2025-06-15 21:32:33.200745 Hh7CK9pc3aI from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop Hh7CK9pc3aI: all done -2025-06-15 21:32:33.200835 Hh7CK9pc3aI from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:38480: Queued from from@from to [to@localhost] - IqTSTE1XJOA -2025-06-15 21:32:33.201071 IqTSTE1XJOA from=from@from queued ip=127.0.0.1:38480 to=[to@localhost] -_ queue.go:308 Queue.SendLoop IqTSTE1XJOA: from from@from -_ queue.go:367 Queue.SendLoop IqTSTE1XJOA: testuser@localhost sent -2025-06-15 21:32:33.201143 IqTSTE1XJOA from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop IqTSTE1XJOA: all done -2025-06-15 21:32:33.201215 IqTSTE1XJOA from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:38480: Queued from from@from to [to@localhost] - KX5rLq2gNA0 -2025-06-15 21:32:33.201448 KX5rLq2gNA0 from=from@from queued ip=127.0.0.1:38480 to=[to@localhost] -_ queue.go:308 Queue.SendLoop KX5rLq2gNA0: from from@from -_ queue.go:367 Queue.SendLoop KX5rLq2gNA0: testuser@localhost sent -2025-06-15 21:32:33.201491 KX5rLq2gNA0 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop KX5rLq2gNA0: all done -2025-06-15 21:32:33.201556 KX5rLq2gNA0 from=from@from all done ---- PASS: TestManyEmails (0.00s) +_ queue.go:342 Queue.SendLoop jF3LC4FU1p0: all done +2024-05-13 15:11:29.327197 jF3LC4FU1p0 from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50366: Queued from from@from to [to@localhost] - cX8EWvvhCyM +2024-05-13 15:11:29.333266 cX8EWvvhCyM from=from@from queued ip=127.0.0.1:50366 to=[to@localhost] +_ queue.go:308 Queue.SendLoop cX8EWvvhCyM: from from@from +_ queue.go:367 Queue.SendLoop cX8EWvvhCyM: testuser@localhost sent +2024-05-13 15:11:29.333488 cX8EWvvhCyM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop cX8EWvvhCyM: all done +2024-05-13 15:11:29.333787 cX8EWvvhCyM from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50366: Queued from from@from to [to@localhost] - qNDWVWFnb0s +2024-05-13 15:11:29.334495 qNDWVWFnb0s from=from@from queued ip=127.0.0.1:50366 to=[to@localhost] +_ queue.go:308 Queue.SendLoop qNDWVWFnb0s: from from@from +_ queue.go:367 Queue.SendLoop qNDWVWFnb0s: testuser@localhost sent +2024-05-13 15:11:29.334712 qNDWVWFnb0s from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop qNDWVWFnb0s: all done +2024-05-13 15:11:29.334972 qNDWVWFnb0s from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50366: Queued from from@from to [to@localhost] - eZ8r990nUi8 +2024-05-13 15:11:29.335624 eZ8r990nUi8 from=from@from queued ip=127.0.0.1:50366 to=[to@localhost] +_ queue.go:308 Queue.SendLoop eZ8r990nUi8: from from@from +_ queue.go:367 Queue.SendLoop eZ8r990nUi8: testuser@localhost sent +2024-05-13 15:11:29.335850 eZ8r990nUi8 from=from@from to=testuser@localhost sent +--- PASS: TestManyEmails (0.01s) === RUN TestAuth -_ queue.go:342 Queue.SendLoop ypWW14uf2Hk: all done -2025-06-15 21:32:33.221023 ypWW14uf2Hk from=from@from all done -2025-06-15 21:32:33.326756 127.0.0.1:44232 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:44232: Queued from testuser@localhost to [to@localhost] - mKucQg1ACXc -2025-06-15 21:32:33.330606 mKucQg1ACXc from=testuser@localhost queued ip=127.0.0.1:44232 to=[to@localhost] -_ queue.go:308 Queue.SendLoop mKucQg1ACXc: from testuser@localhost -_ queue.go:367 Queue.SendLoop mKucQg1ACXc: testuser@localhost sent -2025-06-15 21:32:33.330715 mKucQg1ACXc from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop mKucQg1ACXc: all done -2025-06-15 21:32:33.330835 mKucQg1ACXc from=testuser@localhost all done ---- PASS: TestAuth (0.13s) +_ queue.go:342 Queue.SendLoop eZ8r990nUi8: all done +2024-05-13 15:11:29.336120 eZ8r990nUi8 from=from@from all done +2024-05-13 15:11:29.443726 127.0.0.1:32872 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:32872: Queued from testuser@localhost to [to@localhost] - lOqqi-nWzEU +2024-05-13 15:11:29.444642 lOqqi-nWzEU from=testuser@localhost queued ip=127.0.0.1:32872 to=[to@localhost] +_ queue.go:308 Queue.SendLoop lOqqi-nWzEU: from testuser@localhost +_ queue.go:367 Queue.SendLoop lOqqi-nWzEU: testuser@localhost sent +2024-05-13 15:11:29.444742 lOqqi-nWzEU from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuth (0.11s) +_ queue.go:342 Queue.SendLoop lOqqi-nWzEU: all done +2024-05-13 15:11:29.444869 lOqqi-nWzEU from=testuser@localhost all done === RUN TestSubmissionWithoutAuth -_ conn.go:297 SMTP.Conn 127.0.0.1:44240: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated ---- PASS: TestSubmissionWithoutAuth (0.00s) +_ conn.go:297 SMTP.Conn 127.0.0.1:32882: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +--- PASS: TestSubmissionWithoutAuth (0.01s) === RUN TestAuthOnTLS -2025-06-15 21:32:33.519373 127.0.0.1:54290 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:54290: Queued from testuser@localhost to [to@localhost] - juSlP1fIbrM -2025-06-15 21:32:33.530564 juSlP1fIbrM from=testuser@localhost queued ip=127.0.0.1:54290 to=[to@localhost] -_ queue.go:308 Queue.SendLoop juSlP1fIbrM: from testuser@localhost -_ queue.go:367 Queue.SendLoop juSlP1fIbrM: testuser@localhost sent -2025-06-15 21:32:33.530638 juSlP1fIbrM from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop juSlP1fIbrM: all done -2025-06-15 21:32:33.530755 juSlP1fIbrM from=testuser@localhost all done ---- PASS: TestAuthOnTLS (0.20s) +2024-05-13 15:11:29.556881 127.0.0.1:53946 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:53946: Queued from testuser@localhost to [to@localhost] - ZHRc5tLlnLQ +2024-05-13 15:11:29.557911 ZHRc5tLlnLQ from=testuser@localhost queued ip=127.0.0.1:53946 to=[to@localhost] +_ queue.go:308 Queue.SendLoop ZHRc5tLlnLQ: from testuser@localhost +_ queue.go:367 Queue.SendLoop ZHRc5tLlnLQ: testuser@localhost sent +2024-05-13 15:11:29.558044 ZHRc5tLlnLQ from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop ZHRc5tLlnLQ: all done +2024-05-13 15:11:29.558206 ZHRc5tLlnLQ from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.11s) === RUN TestAuthOnSMTP -2025-06-15 21:32:33.716289 127.0.0.1:38482 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:38482: Queued from testuser@localhost to [to@localhost] - 9Synmzs8k_g -2025-06-15 21:32:33.729187 9Synmzs8k_g from=testuser@localhost queued ip=127.0.0.1:38482 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 9Synmzs8k_g: from testuser@localhost -_ queue.go:367 Queue.SendLoop 9Synmzs8k_g: testuser@localhost sent -2025-06-15 21:32:33.729269 9Synmzs8k_g from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 9Synmzs8k_g: all done -2025-06-15 21:32:33.729369 9Synmzs8k_g from=testuser@localhost all done ---- PASS: TestAuthOnSMTP (0.20s) +2024-05-13 15:11:29.666209 127.0.0.1:50368 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:50368: Queued from testuser@localhost to [to@localhost] - c-UO3DQ2DyE +2024-05-13 15:11:29.666882 c-UO3DQ2DyE from=testuser@localhost queued ip=127.0.0.1:50368 to=[to@localhost] +_ queue.go:308 Queue.SendLoop c-UO3DQ2DyE: from testuser@localhost +_ queue.go:367 Queue.SendLoop c-UO3DQ2DyE: testuser@localhost sent +2024-05-13 15:11:29.667066 c-UO3DQ2DyE from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnSMTP (0.11s) === RUN TestBrokenAuth -_ conn.go:1062 SMTP.Conn 127.0.0.1:44254: error: error authenticating \"user\"@\"broken\": failed to auth -2025-06-15 21:32:33.846166 127.0.0.1:44254 auth failed for user@broken -_ conn.go:297 SMTP.Conn 127.0.0.1:44254: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:297 SMTP.Conn 127.0.0.1:44254: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +_ queue.go:342 Queue.SendLoop c-UO3DQ2DyE: all done +2024-05-13 15:11:29.667421 c-UO3DQ2DyE from=testuser@localhost all done +_ conn.go:1062 SMTP.Conn 127.0.0.1:32896: error: error authenticating \"user\"@\"broken\": failed to auth +2024-05-13 15:11:29.791208 127.0.0.1:32896 auth failed for user@broken +_ conn.go:297 SMTP.Conn 127.0.0.1:32896: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:297 SMTP.Conn 127.0.0.1:32896: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command --- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:297 SMTP.Conn 127.0.0.1:38488: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38488: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38488: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38488: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:38494: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38494: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38494: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38494: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:38506: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38506: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38506: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38506: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:38522: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38522: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38522: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38522: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:38536: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38536: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38536: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38536: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:38548: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:38548: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:38548: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38548: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.00s) +_ conn.go:297 SMTP.Conn 127.0.0.1:50376: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50376: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50376: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50376: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50382: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50382: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50382: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50382: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50386: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50386: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50386: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50386: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50398: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50398: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50398: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50398: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50400: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50400: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50400: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50400: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50404: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:50404: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:50404: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50404: error: too many errors, breaking connection +--- PASS: TestWrongMailParsing (0.01s) === RUN TestNullMailFrom ---- PASS: TestNullMailFrom (0.01s) +--- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:297 SMTP.Conn 127.0.0.1:38562: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:297 SMTP.Conn 127.0.0.1:50416: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.01s) === RUN TestRelayForbidden -2025-06-15 21:32:33.862971 127.0.0.1:38586 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38586: error: RCPT failed: 503 5.7.1 Relay not allowed +2024-05-13 15:11:29.806275 127.0.0.1:50436 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50436: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2025-06-15 21:32:34.065383 127.0.0.1:44256 auth succeeded for testuser@localhost -_ conn.go:297 SMTP.Conn 127.0.0.1:44256: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.22s) +2024-05-13 15:11:29.912364 127.0.0.1:32904 auth succeeded for testuser@localhost +_ conn.go:297 SMTP.Conn 127.0.0.1:32904: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.11s) === RUN TestRcptBrokenExists -_ conn.go:601 SMTP.Conn 127.0.0.1:38588: error: error checking if user \"to@broken\" exists: failed to check if user exists -2025-06-15 21:32:34.085619 127.0.0.1:38588 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists -_ conn.go:297 SMTP.Conn 127.0.0.1:38588: error: RCPT failed: 451 4.4.3 Temporary error checking address ---- PASS: TestRcptBrokenExists (0.00s) +_ conn.go:601 SMTP.Conn 127.0.0.1:50448: error: error checking if user \"to@broken\" exists: failed to check if user exists +2024-05-13 15:11:29.924803 127.0.0.1:50448 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists +_ conn.go:297 SMTP.Conn 127.0.0.1:50448: error: RCPT failed: 451 4.4.3 Temporary error checking address +--- PASS: TestRcptBrokenExists (0.01s) === RUN TestRcptUserDoesNotExist -2025-06-15 21:32:34.094410 127.0.0.1:38596 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:297 SMTP.Conn 127.0.0.1:38596: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +2024-05-13 15:11:29.930408 127.0.0.1:50460 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:297 SMTP.Conn 127.0.0.1:50460: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptUserDoesNotExist (0.01s) === RUN TestTooMuchData -_ conn.go:689 SMTP.Conn 127.0.0.1:38604: Queued from from@from to [to@localhost] - PpTCfaoBJI0 -2025-06-15 21:32:34.264585 PpTCfaoBJI0 from=from@from queued ip=127.0.0.1:38604 to=[to@localhost] -_ queue.go:308 Queue.SendLoop PpTCfaoBJI0: from from@from -_ queue.go:367 Queue.SendLoop PpTCfaoBJI0: testuser@localhost sent -2025-06-15 21:32:34.264758 PpTCfaoBJI0 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop PpTCfaoBJI0: all done -2025-06-15 21:32:34.309309 PpTCfaoBJI0 from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:38604: Queued from from@from to [to@localhost] - Bzd_IwSqcRw -2025-06-15 21:32:34.470460 Bzd_IwSqcRw from=from@from queued ip=127.0.0.1:38604 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Bzd_IwSqcRw: from from@from -_ queue.go:367 Queue.SendLoop Bzd_IwSqcRw: testuser@localhost sent -2025-06-15 21:32:34.470847 Bzd_IwSqcRw from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop Bzd_IwSqcRw: all done -2025-06-15 21:32:34.511851 Bzd_IwSqcRw from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:38604: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:689 SMTP.Conn 127.0.0.1:38604: Queued from from@from to [to@localhost] - ISptMXE2m9s -2025-06-15 21:32:34.849059 ISptMXE2m9s from=from@from queued ip=127.0.0.1:38604 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ISptMXE2m9s: from from@from -_ queue.go:367 Queue.SendLoop ISptMXE2m9s: testuser@localhost sent -2025-06-15 21:32:34.849219 ISptMXE2m9s from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.76s) +_ conn.go:689 SMTP.Conn 127.0.0.1:50474: Queued from from@from to [to@localhost] - 2ntGY4l8Lpo +2024-05-13 15:11:30.082570 2ntGY4l8Lpo from=from@from queued ip=127.0.0.1:50474 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 2ntGY4l8Lpo: from from@from +_ queue.go:367 Queue.SendLoop 2ntGY4l8Lpo: testuser@localhost sent +2024-05-13 15:11:30.082840 2ntGY4l8Lpo from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 2ntGY4l8Lpo: all done +2024-05-13 15:11:30.116956 2ntGY4l8Lpo from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:50474: Queued from from@from to [to@localhost] - RrRpgv11KgI +2024-05-13 15:11:30.219445 RrRpgv11KgI from=from@from queued ip=127.0.0.1:50474 to=[to@localhost] +_ queue.go:308 Queue.SendLoop RrRpgv11KgI: from from@from +_ queue.go:367 Queue.SendLoop RrRpgv11KgI: testuser@localhost sent +2024-05-13 15:11:30.219655 RrRpgv11KgI from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop RrRpgv11KgI: all done +2024-05-13 15:11:30.251279 RrRpgv11KgI from=from@from all done +_ conn.go:297 SMTP.Conn 127.0.0.1:50474: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:689 SMTP.Conn 127.0.0.1:50474: Queued from from@from to [to@localhost] - aISSsbWiaxY +2024-05-13 15:11:30.517529 aISSsbWiaxY from=from@from queued ip=127.0.0.1:50474 to=[to@localhost] +_ queue.go:308 Queue.SendLoop aISSsbWiaxY: from from@from +_ queue.go:367 Queue.SendLoop aISSsbWiaxY: testuser@localhost sent +2024-05-13 15:11:30.517737 aISSsbWiaxY from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.59s) === RUN TestSimpleCommands -_ conn.go:297 SMTP.Conn 127.0.0.1:38608: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. -_ conn.go:297 SMTP.Conn 127.0.0.1:38608: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:297 SMTP.Conn 127.0.0.1:50480: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. +_ conn.go:297 SMTP.Conn 127.0.0.1:50480: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. --- PASS: TestSimpleCommands (0.00s) === RUN TestLongLines -_ conn.go:297 SMTP.Conn 127.0.0.1:38624: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:327 SMTP.Conn 127.0.0.1:38624: error: exiting with error: line too long +_ conn.go:297 SMTP.Conn 127.0.0.1:50488: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:327 SMTP.Conn 127.0.0.1:50488: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.00s) === RUN TestRepeatedStartTLS -_ conn.go:297 SMTP.Conn 127.0.0.1:38644: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:297 SMTP.Conn 127.0.0.1:50512: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestRepeatedStartTLS (0.01s) === RUN TestStartTLSOnTLS -_ conn.go:297 SMTP.Conn 127.0.0.1:54302: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:297 SMTP.Conn 127.0.0.1:53950: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestStartTLSOnTLS (0.00s) === RUN FuzzConnection === RUN FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 -_ conn.go:297 SMTP.Conn 127.0.0.1:38650: error: RCPT failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:38650: error: RCPT failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:38650: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:38650: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50514: error: RCPT failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:50514: error: RCPT failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:50514: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:50514: error: too many errors, breaking connection === RUN FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 -_ conn.go:297 SMTP.Conn 127.0.0.1:38656: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:38656: error: DATA failed: 503 5.5.1 Sender not yet given -2025-06-15 21:32:34.874364 127.0.0.1:38656 rejected from=a@b to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38656: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:304 SMTP.Conn 127.0.0.1:38656: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50516: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:50516: error: DATA failed: 503 5.5.1 Sender not yet given +2024-05-13 15:11:30.532294 127.0.0.1:50516 rejected from=a@b to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50516: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:304 SMTP.Conn 127.0.0.1:50516: error: too many errors, breaking connection === RUN FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f -2025-06-15 21:32:34.874773 127.0.0.1:38672 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38672: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38672: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:38672: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:38672: error: too many errors, breaking connection +2024-05-13 15:11:30.532874 127.0.0.1:50522 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50522: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50522: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:50522: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:50522: error: too many errors, breaking connection === RUN FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe -_ conn.go:297 SMTP.Conn 127.0.0.1:38676: error: MAIL failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:38676: error: MAIL failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:38676: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:304 SMTP.Conn 127.0.0.1:38676: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50536: error: MAIL failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:50536: error: MAIL failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:50536: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:304 SMTP.Conn 127.0.0.1:50536: error: too many errors, breaking connection === RUN FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 -_ conn.go:297 SMTP.Conn 127.0.0.1:38678: error: HELO failed: 501 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:38678: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:50538: error: HELO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:50538: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:327 SMTP.Conn 127.0.0.1:50538: error: exiting with error: read tcp 127.0.0.1:43019->127.0.0.1:50538: read: connection reset by peer === RUN FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf === RUN FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 -2025-06-15 21:32:34.875726 127.0.0.1:38700 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38700: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:38700: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:38700: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:38700: error: too many errors, breaking connection +2024-05-13 15:11:30.534766 127.0.0.1:50558 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50558: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:50558: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:50558: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:50558: error: too many errors, breaking connection === RUN FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 -_ conn.go:297 SMTP.Conn 127.0.0.1:38716: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:38716: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ queue.go:342 Queue.SendLoop ISptMXE2m9s: all done -2025-06-15 21:32:34.880235 ISptMXE2m9s from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:38716: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:304 SMTP.Conn 127.0.0.1:38716: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:50574: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:50574: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:50574: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:304 SMTP.Conn 127.0.0.1:50574: error: too many errors, breaking connection === RUN FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded === RUN FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 -_ conn.go:297 SMTP.Conn 127.0.0.1:38730: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:50580: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:50584: error: AUTH failed: 503 5.7.10 You feel vulnerable === RUN FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c -_ conn.go:297 SMTP.Conn 127.0.0.1:38742: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:54316: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage -_ conn.go:297 SMTP.Conn 127.0.0.1:54316: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 -2025-06-15 21:32:34.998117 127.0.0.1:54316 auth failed for user@testserver -_ conn.go:297 SMTP.Conn 127.0.0.1:54316: error: AUTH failed: 535 5.7.8 Incorrect user or password -_ conn.go:304 SMTP.Conn 127.0.0.1:54316: error: too many errors, breaking connection ---- PASS: FuzzConnection (0.13s) +_ conn.go:297 SMTP.Conn 127.0.0.1:53960: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage +_ conn.go:297 SMTP.Conn 127.0.0.1:53960: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 +_ queue.go:342 Queue.SendLoop aISSsbWiaxY: all done +2024-05-13 15:11:30.551117 aISSsbWiaxY from=from@from all done +2024-05-13 15:11:30.643716 127.0.0.1:53960 auth failed for user@testserver +_ conn.go:297 SMTP.Conn 127.0.0.1:53960: error: AUTH failed: 535 5.7.8 Incorrect user or password +_ conn.go:304 SMTP.Conn 127.0.0.1:53960: error: too many errors, breaking connection +--- PASS: FuzzConnection (0.11s) --- PASS: FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 (0.00s) - --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.01s) + --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.00s) --- PASS: FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f (0.00s) --- PASS: FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe (0.00s) --- PASS: FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 (0.00s) --- PASS: FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf (0.00s) --- PASS: FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 (0.00s) - --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.01s) + --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.00s) --- PASS: FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded (0.00s) --- PASS: FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 (0.00s) - --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.12s) + --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.11s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.325s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.605s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -1586,16 +1623,16 @@ --- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.06s) +--- PASS: TestPolicyTooBig (0.01s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_1592375416" + testlib.go:32: test directory: "/tmp/testlib_3947652286" 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.02s) +--- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_1024423459" + testlib.go:32: test directory: "/tmp/testlib_288977671" 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} @@ -1604,13 +1641,13 @@ sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} --- PASS: TestCacheBadData (0.00s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_808651016" + testlib.go:32: test directory: "/tmp/testlib_3522043444" 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_3518321459" + testlib.go:32: test directory: "/tmp/testlib_3492620388" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1620,9 +1657,9 @@ === RUN TestHTTPGet --- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.170s +ok blitiri.com.ar/go/chasquid/internal/sts 0.060s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_25354958" + testlib.go:32: test directory: "/tmp/testlib_1716982189" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -1633,54 +1670,54 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_2712477014" + testlib.go:32: test directory: "/tmp/testlib_4200952651" --- 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_1095499887" + testlib.go:32: test directory: "/tmp/testlib_1573921565" --- PASS: TestGenerateCert (0.05s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.12s) +--- PASS: TestGenerateCertBadDir (0.02s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.240s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.115s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.040s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.007s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test2714098342" - userdb_test.go:37: file: "/tmp/userdb_test1788032049" + userdb_test.go:37: file: "/tmp/userdb_test3631965343" + userdb_test.go:37: file: "/tmp/userdb_test364442270" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test2373106732" ---- PASS: TestWrite (1.34s) + userdb_test.go:37: file: "/tmp/userdb_test3251232114" +--- PASS: TestWrite (0.62s) === RUN TestNew ---- PASS: TestNew (0.15s) +--- PASS: TestNew (0.06s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test4071868968" + userdb_test.go:37: file: "/tmp/userdb_test1286930518" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test141375211" + userdb_test.go:37: file: "/tmp/userdb_test371740773" --- PASS: TestPlainScheme (0.00s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test3513119151" + userdb_test.go:37: file: "/tmp/userdb_test462581503" --- PASS: TestDeniedScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test3672406258" + userdb_test.go:37: file: "/tmp/userdb_test3196296072" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test1095550766" ---- PASS: TestRemoveUser (0.22s) + userdb_test.go:37: file: "/tmp/userdb_test3097900123" +--- PASS: TestRemoveUser (0.10s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test1749326453" ---- PASS: TestExists (0.17s) + userdb_test.go:37: file: "/tmp/userdb_test2928927969" +--- PASS: TestExists (0.09s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.936s +ok blitiri.com.ar/go/chasquid/internal/userdb 0.887s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1734,8 +1771,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.13-1_arm64.deb'. dpkg-deb: building package 'chasquid' in '../chasquid_1.13-1_arm64.deb'. +dpkg-deb: building package 'chasquid-dbgsym' in '../chasquid-dbgsym_1.13-1_arm64.deb'. dpkg-genbuildinfo --build=binary -O../chasquid_1.13-1_arm64.buildinfo dpkg-genchanges --build=binary -O../chasquid_1.13-1_arm64.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -1743,12 +1780,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/3978512/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/3978512/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/1655212 and its subdirectories -I: Current time: Sun Jun 15 09:33:28 -12 2025 -I: pbuilder-time-stamp: 1750023208 +I: removing directory /srv/workspace/pbuilder/3978512 and its subdirectories +I: Current time: Tue May 14 05:11:50 +14 2024 +I: pbuilder-time-stamp: 1715613110