Diff of the two buildlogs: -- --- b1/build.log 2024-05-15 23:39:22.040660162 +0000 +++ b2/build.log 2024-05-15 23:42:36.976365430 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed May 15 11:36:25 -12 2024 -I: pbuilder-time-stamp: 1715816185 +I: Current time: Wed Jun 18 20:02:27 +14 2025 +I: pbuilder-time-stamp: 1750226547 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-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/2199066/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/3692366/tmp/hooks/D01_modify_environment starting +debug: Running on codethink01-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 Jun 18 06:02 /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/3692366/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/3692366/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='trixie' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='a0803ab60215451badfa4181bbdc44a9' - 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='2199066' - PS1='# ' - PS2='> ' + INVOCATION_ID=f10e2270db0f415e9bc33038062ba8b0 + 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=3692366 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.3vDVWpbg/pbuilderrc_hcSp --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3vDVWpbg/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.3vDVWpbg/pbuilderrc_lTBb --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.3vDVWpbg/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 codethink04-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 May 14 11:23 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2199066/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 16 17:48 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/3692366/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 trixie/main arm64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 45 http://deb.debian.org/debian trixie/main arm64 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 46 http://deb.debian.org/debian trixie/main arm64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 66.7 MB in 1s (108 MB/s) +Fetched 66.7 MB in 1s (101 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/3692366/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/3692366/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,33 +432,33 @@ 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/goos internal/goarch encoding internal/coverage/rtcov -unicode/utf8 -internal/unsafeheader internal/godebugs internal/goexperiment +internal/unsafeheader +internal/goos +internal/race +unicode/utf8 +internal/itoa math/bits runtime/internal/math -internal/itoa internal/cpu +cmp +internal/chacha8rand +log/internal +runtime/internal/syscall +unicode runtime/internal/sys -internal/race sync/atomic -unicode internal/abi -internal/chacha8rand -cmp -runtime/internal/syscall -log/internal -internal/nettrace +google.golang.org/protobuf/internal/flags unicode/utf16 +internal/nettrace math -google.golang.org/protobuf/internal/set -google.golang.org/protobuf/internal/flags slices +google.golang.org/protobuf/internal/set container/ring container/list crypto/internal/alias @@ -438,42 +474,42 @@ sync internal/testlog internal/bisect -google.golang.org/protobuf/internal/pragma internal/singleflight +google.golang.org/protobuf/internal/pragma runtime/cgo internal/godebug errors -strconv sort -crypto/internal/nistec/fiat -internal/safefilepath -vendor/golang.org/x/net/dns/dnsmessage +strconv io -path internal/intern -math/rand internal/oserror +internal/safefilepath +math/rand +path +vendor/golang.org/x/net/dns/dnsmessage +crypto/internal/nistec/fiat syscall -golang.org/x/text/internal/tag strings -hash -bytes crypto/internal/randutil +bytes text/tabwriter +hash hash/fnv hash/crc32 -net/netip -reflect -crypto -crypto/rc4 +golang.org/x/text/internal/tag +html +net/http/internal/ascii +bufio vendor/golang.org/x/text/transform golang.org/x/text/transform +reflect +net/netip +regexp/syntax +crypto/rc4 +crypto golang.org/x/text/runes golang.org/x/text/width -bufio -html -regexp/syntax -net/http/internal/ascii internal/syscall/unix time internal/syscall/execenv @@ -482,8 +518,8 @@ io/fs context embed -os google.golang.org/protobuf/internal/editiondefaults +os internal/fmtsort encoding/binary encoding/base64 @@ -493,101 +529,105 @@ crypto/md5 vendor/golang.org/x/crypto/internal/poly1305 golang.org/x/sys/unix +path/filepath +google.golang.org/protobuf/internal/detrand +fmt +net +io/ioutil +blitiri.com.ar/go/chasquid/internal/safeio +internal/lazyregexp +os/signal encoding/pem +internal/sysinfo +crypto/internal/edwards25519 crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 -crypto/internal/edwards25519 +os/exec crypto/aes crypto/sha512 crypto/hmac crypto/sha1 crypto/sha256 -vendor/golang.org/x/crypto/chacha20poly1305 vendor/golang.org/x/crypto/hkdf +vendor/golang.org/x/crypto/chacha20poly1305 golang.org/x/crypto/pbkdf2 -fmt -net -path/filepath -google.golang.org/protobuf/internal/detrand -io/ioutil -blitiri.com.ar/go/chasquid/internal/safeio -internal/lazyregexp -os/signal -internal/sysinfo -os/exec -golang.org/x/crypto/scrypt -crypto/ecdh flag +google.golang.org/protobuf/internal/version log +google.golang.org/protobuf/internal/errors go/token +net/url compress/flate -math/big encoding/json -google.golang.org/protobuf/internal/version -net/url text/template/parse -google.golang.org/protobuf/internal/errors -encoding/hex +math/big google.golang.org/protobuf/encoding/protowire +crypto/ecdh +google.golang.org/protobuf/reflect/protoreflect +encoding/hex +vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack -vendor/golang.org/x/text/unicode/bidi -google.golang.org/protobuf/reflect/protoreflect mime mime/quotedprintable net/http/internal +compress/gzip blitiri.com.ar/go/chasquid/internal/envelope golang.org/x/text/unicode/bidi golang.org/x/text/unicode/norm -compress/gzip golang.org/x/text/internal/language -text/template vendor/golang.org/x/text/secure/bidirule blitiri.com.ar/go/chasquid/internal/tlsconst -runtime/trace -internal/profile -golang.org/x/text/secure/bidirule -runtime/pprof -runtime/debug -golang.org/x/text/internal/language/compact 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/runtime/protoiface -google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts -golang.org/x/term +text/template +golang.org/x/text/secure/bidirule +golang.org/x/crypto/scrypt +google.golang.org/protobuf/proto +internal/profile +runtime/pprof +runtime/trace +google.golang.org/protobuf/internal/encoding/defval +runtime/debug +google.golang.org/protobuf/encoding/prototext +google.golang.org/protobuf/internal/filedesc +golang.org/x/text/internal/language/compact testing +vendor/golang.org/x/net/idna +golang.org/x/net/idna golang.org/x/text/language +html/template +blitiri.com.ar/go/chasquid/internal/protoio crypto/rand -google.golang.org/protobuf/proto crypto/elliptic crypto/internal/bigmod -google.golang.org/protobuf/internal/encoding/defval crypto/internal/boring/bbig encoding/asn1 crypto/ed25519 crypto/dsa -vendor/golang.org/x/net/idna +golang.org/x/text/internal crypto/rsa -html/template +golang.org/x/text/cases +golang.org/x/term vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix -golang.org/x/net/idna -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/filedesc -golang.org/x/text/internal -golang.org/x/text/cases +google.golang.org/protobuf/internal/encoding/tag crypto/ecdsa -blitiri.com.ar/go/chasquid/internal/protoio golang.org/x/text/secure/precis -google.golang.org/protobuf/internal/encoding/tag -blitiri.com.ar/go/chasquid/internal/normalize google.golang.org/protobuf/internal/impl +blitiri.com.ar/go/chasquid/internal/normalize +google.golang.org/protobuf/internal/filetype +google.golang.org/protobuf/runtime/protoimpl +blitiri.com.ar/go/chasquid/internal/userdb +blitiri.com.ar/go/chasquid/internal/protoio/testpb log/syslog crypto/x509 net/textproto @@ -597,15 +637,11 @@ 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 -blitiri.com.ar/go/chasquid/cmd/mda-lmtp net/mail -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/runtime/protoimpl +mime/multipart +blitiri.com.ar/go/chasquid/cmd/mda-lmtp blitiri.com.ar/go/chasquid/internal/config -blitiri.com.ar/go/chasquid/internal/userdb -blitiri.com.ar/go/chasquid/internal/protoio/testpb crypto/tls net/http/httptrace net/smtp @@ -616,17 +652,17 @@ expvar golang.org/x/net/context/ctxhttp net/http/pprof -blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/trace +blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/domaininfo -blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/localrpc blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/aliases 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 @@ -656,17 +692,17 @@ === RUN TestTooMuchRecursionOnCatchAll --- PASS: TestTooMuchRecursionOnCatchAll (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.01s) +--- PASS: TestAddFile (0.00s) === RUN TestRichFile --- PASS: TestRichFile (0.00s) === RUN TestManyFiles ---- PASS: TestManyFiles (0.00s) +--- PASS: TestManyFiles (0.01s) === RUN TestHookError _ aliases.go:547 Hook.Alias-Resolve a@localA: error: exit status 1 _ 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.05s) +--- PASS: TestHookError (0.08s) === 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.116s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.152s === 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.85s) +--- PASS: TestAuthenticate (0.58s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.20s) +--- PASS: TestMultipleBackends (0.19s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload @@ -728,28 +764,28 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 1.146s +ok blitiri.com.ar/go/chasquid/internal/auth 0.828s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_2416074623" + testlib.go:32: test directory: "/tmp/testlib_3160385345" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_755455750" + testlib.go:32: test directory: "/tmp/testlib_1240914555" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_1938921248" + testlib.go:32: test directory: "/tmp/testlib_2009736470" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_2834337762" + testlib.go:32: test directory: "/tmp/testlib_3557499122" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.072s +ok blitiri.com.ar/go/chasquid/internal/config 0.088s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_3423060165" + testlib.go:32: test directory: "/tmp/testlib_2946492554" --- PASS: TestMDA (0.02s) === RUN TestMDATimeout _ mda.go:73 Courier.MDA to@local: error: operation timed out @@ -757,17 +793,17 @@ === 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.02s) +--- PASS: TestMDABadCommandLine (0.01s) === RUN TestExitCode mda_test.go:71: util/exitcode not found, running from outside repo? --- SKIP: TestExitCode (0.00s) === RUN TestSanitize --- PASS: TestSanitize (0.00s) === RUN TestSMTP - testlib.go:32: test directory: "/tmp/testlib_3257102705" - testlib.go:32: test directory: "/tmp/testlib_242075110" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::44533: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::44533: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_704632160" + testlib.go:32: test directory: "/tmp/testlib_4254419255" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::34393: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::34393: 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:" @@ -777,24 +813,24 @@ fakeserver_test.go:105: fakeServer exiting: EOF --- PASS: TestSMTP (0.08s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_3333874627" - testlib.go:32: test directory: "/tmp/testlib_130424706" + testlib.go:32: test directory: "/tmp/testlib_3604672568" + testlib.go:32: test directory: "/tmp/testlib_3167106818" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:45464->127.0.0.1:36773: 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:45464->127.0.0.1:36773: 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:45464->127.0.0.1:36773: 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:45464->127.0.0.1:36773: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:48428->127.0.0.1:34409: 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:48428->127.0.0.1:34409: 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:48428->127.0.0.1:34409: 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:48428->127.0.0.1:34409: i/o timeout) fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_907436244" - testlib.go:32: test directory: "/tmp/testlib_4040997257" + testlib.go:32: test directory: "/tmp/testlib_492112703" + testlib.go:32: test directory: "/tmp/testlib_333252857" 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_2810782989" - testlib.go:32: test directory: "/tmp/testlib_821680609" + testlib.go:32: test directory: "/tmp/testlib_2194650797" + testlib.go:32: test directory: "/tmp/testlib_2833198259" 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 +838,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_880328578" - testlib.go:32: test directory: "/tmp/testlib_2912266636" + testlib.go:32: test directory: "/tmp/testlib_2748752181" + testlib.go:32: test directory: "/tmp/testlib_2841850984" 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 +848,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_1217595186" - testlib.go:32: test directory: "/tmp/testlib_1820237440" + testlib.go:32: test directory: "/tmp/testlib_1228013674" + testlib.go:32: test directory: "/tmp/testlib_2425636940" 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 +858,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.72s) +--- PASS: TestSMTPErrors (1.67s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_932345298" + testlib.go:32: test directory: "/tmp/testlib_1681557313" _ 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 +875,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_689105175" - testlib.go:32: test directory: "/tmp/testlib_933448104" + testlib.go:32: test directory: "/tmp/testlib_2449869386" + testlib.go:32: test directory: "/tmp/testlib_2071956925" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -851,7 +887,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_2611471236" + testlib.go:32: test directory: "/tmp/testlib_4239035583" 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,16 +895,16 @@ _ 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.13s) +--- PASS: TestTLS (0.47s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_3684356320" - testlib.go:32: test directory: "/tmp/testlib_30778048" + testlib.go:32: test directory: "/tmp/testlib_261381636" + testlib.go:32: test directory: "/tmp/testlib_1525028182" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" _ smtp.go:171 Courier.SMTP to@to: error: TLS error, retrying without TLS: 500 starttls err - fakeserver_test.go:105: fakeServer exiting: EOF fakeserver_test.go:98: fakeServer got connection + fakeserver_test.go:105: fakeServer exiting: EOF fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" fakeserver_test.go:109: fakeServer read: "RCPT TO:" @@ -877,8 +913,8 @@ fakeserver_test.go:105: fakeServer exiting: EOF --- PASS: TestTLSError (0.10s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_2758670999" - testlib.go:32: test directory: "/tmp/testlib_2888892481" + testlib.go:32: test directory: "/tmp/testlib_347315502" + testlib.go:32: test directory: "/tmp/testlib_1749678419" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -886,7 +922,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_3578749791" + testlib.go:32: test directory: "/tmp/testlib_2021249505" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -897,11 +933,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.38s) +--- PASS: TestSTSPolicyEnforcement (0.19s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.616s +ok blitiri.com.ar/go/chasquid/internal/courier 2.713s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_3765402908" + testlib.go:32: test directory: "/tmp/testlib_508011031" _ 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 +946,14 @@ _ domaininfo.go:182 DomainInfo.SetToPlain d1: set to plain --- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_3392477616" + testlib.go:32: test directory: "/tmp/testlib_162247378" _ 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_2795512374" + testlib.go:32: test directory: "/tmp/testlib_2555480780" _ 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 @@ -930,29 +966,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.01s) +--- PASS: TestProgressions (0.00s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_3812126741" + testlib.go:32: test directory: "/tmp/testlib_1744207671" _ 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_1221109429" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_1221109429/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_1221109429/db/.s:d1160884559: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_1506203730" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_1506203730/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_1506203730/db/.s:d12622298571: no such file or directory --- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.079s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.065s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_838767878" + testlib.go:32: test directory: "/tmp/testlib_3065360869" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.112s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.041s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -960,13 +996,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.060s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.036s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.067s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.046s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -991,21 +1027,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.060s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.056s === RUN TestBadServer - asm_arm64.s:1222: FakeServer &{{0x4000186480}}: accepted - asm_arm64.s:1222: FakeServer &{{0x4000186480}}: readRequest: "" "" / EOF - asm_arm64.s:1222: FakeServer &{{0x4000186480}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-3310747542/rpc.sock->@: write: broken pipe - asm_arm64.s:1222: FakeServer &{{0x4000186480}}: closing - asm_arm64.s:1222: FakeServer &{{0x4000186600}}: accepted - asm_arm64.s:1222: FakeServer &{{0x4000186600}}: readRequest: "Echo" "" / - asm_arm64.s:1222: FakeServer &{{0x4000186600}}: writeMessage("xxx"): 3 - asm_arm64.s:1222: FakeServer &{{0x4000186600}}: closing ---- PASS: TestBadServer (0.01s) + asm_arm64.s:1222: FakeServer &{{0x40001cf000}}: accepted + asm_arm64.s:1222: FakeServer &{{0x40001cf000}}: readRequest: "" "" / EOF + asm_arm64.s:1222: FakeServer &{{0x40001cf000}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-3205832497/rpc.sock->@: write: broken pipe + asm_arm64.s:1222: FakeServer &{{0x40001cf000}}: closing + asm_arm64.s:1222: FakeServer &{{0x40001cf180}}: accepted + asm_arm64.s:1222: FakeServer &{{0x40001cf180}}: readRequest: "Echo" "" / + asm_arm64.s:1222: FakeServer &{{0x40001cf180}}: writeMessage("xxx"): 3 + asm_arm64.s:1222: FakeServer &{{0x40001cf180}}: closing +--- PASS: TestBadServer (0.02s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-4213638536/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-2512821309/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1013,7 +1049,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-4213638536/sock: error: Accept error: accept unix /tmp/rpc-test-4213638536/sock: use of closed network connection +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-2512821309/sock: error: Accept error: accept unix /tmp/rpc-test-2512821309/sock: use of closed network connection --- PASS: TestEndToEnd (0.01s) --- PASS: TestEndToEnd/Echo (0.00s) --- PASS: TestEndToEnd/Echo#01 (0.00s) @@ -1028,15 +1064,15 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.079s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.099s === RUN TestLogger ---- PASS: TestLogger (0.00s) +--- PASS: TestLogger (0.01s) === RUN TestDefault --- PASS: TestDefault (0.00s) === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.064s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.048s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1044,15 +1080,15 @@ === RUN TestHistogramEmpty --- PASS: TestHistogramEmpty (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.02s) +--- PASS: TestHTTP (0.03s) === RUN TestHTTPLong --- PASS: TestHTTPLong (0.00s) === RUN TestHTTPErrors ---- PASS: TestHTTPErrors (0.04s) +--- PASS: TestHTTPErrors (0.05s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.01s) +--- PASS: TestHTTPUroboro (0.02s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.01s) +--- PASS: TestHTTPDeep (0.02s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler @@ -1072,7 +1108,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.162s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.169s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1132,40 +1168,40 @@ --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.049s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.057s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_1776566829" + testlib.go:32: test directory: "/tmp/testlib_4178059843" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_1515433115" + testlib.go:32: test directory: "/tmp/testlib_1953273617" --- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_965337618" + testlib.go:32: test directory: "/tmp/testlib_3822891126" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_196476203" + testlib.go:32: test directory: "/tmp/testlib_2353401785" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_915855655" + testlib.go:32: test directory: "/tmp/testlib_4233828323" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.088s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.064s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Wed, 15 May 2024 23:38:41 +0000 + Message-ID: + Date: Wed, 18 Jun 2025 06:04:42 +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="RXmWDIqWavM" + boundary="_8e7OTh-MVA" - --RXmWDIqWavM + --_8e7OTh-MVA Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1190,7 +1226,7 @@ oh! the humanity! - --RXmWDIqWavM + --_8e7OTh-MVA Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1221,7 +1257,7 @@ - --RXmWDIqWavM + --_8e7OTh-MVA Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1231,73 +1267,73 @@ Data ñaca. - --RXmWDIqWavM-- + --_8e7OTh-MVA-- ---- PASS: TestDSN (0.00s) +--- PASS: TestDSN (0.01s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_823340622" -_ queue.go:308 Queue.SendLoop 9JZXV3M_dpY: from from -_ queue.go:367 Queue.SendLoop 9JZXV3M_dpY: nodomain sent -_ queue.go:367 Queue.SendLoop 9JZXV3M_dpY: am@loco sent -_ queue.go:367 Queue.SendLoop 9JZXV3M_dpY: x@remote sent -_ queue.go:342 Queue.SendLoop 9JZXV3M_dpY: all done + testlib.go:32: test directory: "/tmp/testlib_1522477132" +_ queue.go:308 Queue.SendLoop Sby4CIBu-Qw: from from +_ queue.go:367 Queue.SendLoop Sby4CIBu-Qw: nodomain sent +_ queue.go:367 Queue.SendLoop Sby4CIBu-Qw: am@loco sent +_ queue.go:367 Queue.SendLoop Sby4CIBu-Qw: x@remote sent +_ queue.go:342 Queue.SendLoop Sby4CIBu-Qw: all done --- PASS: TestBasic (0.00s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_2373899771" -_ queue.go:308 Queue.SendLoop kMqODQa5cak: from from@loco -_ queue.go:465 Queue.SendLoop kMqODQa5cak: queued DSN: IUbuanAFhJc -_ queue.go:342 Queue.SendLoop kMqODQa5cak: all done -_ queue.go:308 Queue.SendLoop IUbuanAFhJc: from <> -_ queue.go:367 Queue.SendLoop IUbuanAFhJc: from@loco sent -_ queue.go:342 Queue.SendLoop IUbuanAFhJc: all done + testlib.go:32: test directory: "/tmp/testlib_2679396134" +_ queue.go:308 Queue.SendLoop XxAucHpqxdE: from from@loco +_ queue.go:465 Queue.SendLoop XxAucHpqxdE: queued DSN: 9IKA7KyKpDY +_ queue.go:342 Queue.SendLoop XxAucHpqxdE: all done +_ queue.go:308 Queue.SendLoop 9IKA7KyKpDY: from <> +_ queue.go:367 Queue.SendLoop 9IKA7KyKpDY: from@loco sent +_ queue.go:342 Queue.SendLoop 9IKA7KyKpDY: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_2246064382" -_ queue.go:308 Queue.SendLoop gFsqyL0Tjlk: from from -_ queue.go:367 Queue.SendLoop gFsqyL0Tjlk: ata@hualpa sent -_ queue.go:367 Queue.SendLoop gFsqyL0Tjlk: pq@loco sent -_ queue.go:367 Queue.SendLoop gFsqyL0Tjlk: rs@loco sent -_ queue.go:342 Queue.SendLoop gFsqyL0Tjlk: all done + testlib.go:32: test directory: "/tmp/testlib_2164088502" +_ queue.go:308 Queue.SendLoop E5PrI8hD9KU: from from +_ queue.go:367 Queue.SendLoop E5PrI8hD9KU: ata@hualpa sent +_ queue.go:367 Queue.SendLoop E5PrI8hD9KU: pq@loco sent +_ queue.go:367 Queue.SendLoop E5PrI8hD9KU: rs@loco sent +_ queue.go:342 Queue.SendLoop E5PrI8hD9KU: all done --- PASS: TestAliases (0.00s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_472163867" + testlib.go:32: test directory: "/tmp/testlib_1253123062" _ queue.go:164 Queue.Put from: error: queue full --- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_293740426" -_ queue.go:308 Queue.SendLoop UlYrmr2kif4: from from -_ queue.go:367 Queue.SendLoop UlYrmr2kif4: to sent -_ queue.go:375 Queue.SendLoop UlYrmr2kif4: error: failed to write: open /tmp/testlib_472163867/.m:UlYrmr2kif41565354835: no such file or directory -_ queue.go:342 Queue.SendLoop UlYrmr2kif4: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_472163867/m:UlYrmr2kif4": remove /tmp/testlib_472163867/m:UlYrmr2kif4: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_707434051" +_ queue.go:308 Queue.SendLoop u9PsXg-ffZk: from from +_ queue.go:367 Queue.SendLoop u9PsXg-ffZk: to sent +_ queue.go:375 Queue.SendLoop u9PsXg-ffZk: error: failed to write: open /tmp/testlib_1253123062/.m:u9PsXg-ffZk932956750: no such file or directory +_ queue.go:342 Queue.SendLoop u9PsXg-ffZk: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_1253123062/m:u9PsXg-ffZk": remove /tmp/testlib_1253123062/m:u9PsXg-ffZk: no such file or directory --- PASS: TestPipes (0.02s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_322112452" -_ queue.go:308 Queue.SendLoop MflhX9c5onY: from from@loco -_ queue.go:367 Queue.SendLoop MflhX9c5onY: to@to sent -_ queue.go:342 Queue.SendLoop MflhX9c5onY: all done + testlib.go:32: test directory: "/tmp/testlib_3838054677" +_ queue.go:308 Queue.SendLoop yYB1Bnmlqhk: from from@loco +_ queue.go:367 Queue.SendLoop yYB1Bnmlqhk: to@to sent +_ queue.go:342 Queue.SendLoop yYB1Bnmlqhk: 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.107s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_3401970634" + testlib.go:32: test directory: "/tmp/testlib_1768785046" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_3775574400" + testlib.go:32: test directory: "/tmp/testlib_3594205" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_2644173095" + testlib.go:32: test directory: "/tmp/testlib_2066588087" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.060s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.044s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.044s +ok blitiri.com.ar/go/chasquid/internal/set 0.036s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1311,16 +1347,16 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.08s) +--- PASS: TestLineTooLong (0.21s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.140s +ok blitiri.com.ar/go/chasquid/internal/smtp 0.269s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] -_ server.go:242 Server listening on 127.0.0.1:36599 (SMTP) -2024-05-15 23:38:42.473377 daemon listening on 127.0.0.1:36599 -_ server.go:242 Server listening on 127.0.0.1:35053 (submission) -2024-05-15 23:38:42.473454 daemon listening on 127.0.0.1:35053 -_ server.go:242 Server listening on 127.0.0.1:38429 (submission+TLS) -2024-05-15 23:38:42.473474 daemon listening on 127.0.0.1:38429 +_ server.go:242 Server listening on 127.0.0.1:35873 (SMTP) +2025-06-18 06:04:43.817043 daemon listening on 127.0.0.1:35873 +_ server.go:242 Server listening on 127.0.0.1:41303 (submission) +2025-06-18 06:04:43.817121 daemon listening on 127.0.0.1:41303 +_ server.go:242 Server listening on 127.0.0.1:45811 (submission+TLS) +2025-06-18 06:04:43.817139 daemon listening on 127.0.0.1:45811 === 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 @@ -1337,227 +1373,227 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:188 SMTP.Conn 127.0.0.1:59342: error: error completing TLS handshake: EOF -_ conn.go:689 SMTP.Conn 127.0.0.1:48562: Queued from from@from to [to@localhost] - VhifdIEHDwc -2024-05-15 23:38:42.577926 VhifdIEHDwc from=from@from queued ip=127.0.0.1:48562 to=[to@localhost] -_ queue.go:308 Queue.SendLoop VhifdIEHDwc: from from@from -_ queue.go:367 Queue.SendLoop VhifdIEHDwc: testuser@localhost sent -2024-05-15 23:38:42.578011 VhifdIEHDwc from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop VhifdIEHDwc: all done -2024-05-15 23:38:42.578198 VhifdIEHDwc from=from@from all done +_ conn.go:188 SMTP.Conn 127.0.0.1:60784: error: error completing TLS handshake: EOF +_ conn.go:689 SMTP.Conn 127.0.0.1:52480: Queued from from@from to [to@localhost] - gBo-ndZLkJM +2025-06-18 06:04:43.921859 gBo-ndZLkJM from=from@from queued ip=127.0.0.1:52480 to=[to@localhost] +_ queue.go:308 Queue.SendLoop gBo-ndZLkJM: from from@from +_ queue.go:367 Queue.SendLoop gBo-ndZLkJM: testuser@localhost sent +2025-06-18 06:04:43.921943 gBo-ndZLkJM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop gBo-ndZLkJM: all done +2025-06-18 06:04:43.922031 gBo-ndZLkJM from=from@from all done --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ conn.go:689 SMTP.Conn 127.0.0.1:48578: Queued from from@from to [to@localhost] - Et_7HAf1VF8 -2024-05-15 23:38:42.588956 Et_7HAf1VF8 from=from@from queued ip=127.0.0.1:48578 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Et_7HAf1VF8: from from@from -_ queue.go:367 Queue.SendLoop Et_7HAf1VF8: testuser@localhost sent -2024-05-15 23:38:42.589025 Et_7HAf1VF8 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop Et_7HAf1VF8: all done -2024-05-15 23:38:42.589116 Et_7HAf1VF8 from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:52494: Queued from from@from to [to@localhost] - mzUrvCu7f-o +2025-06-18 06:04:43.929202 mzUrvCu7f-o from=from@from queued ip=127.0.0.1:52494 to=[to@localhost] +_ queue.go:308 Queue.SendLoop mzUrvCu7f-o: from from@from +_ queue.go:367 Queue.SendLoop mzUrvCu7f-o: testuser@localhost sent +2025-06-18 06:04:43.929266 mzUrvCu7f-o from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop mzUrvCu7f-o: all done +2025-06-18 06:04:43.929366 mzUrvCu7f-o from=from@from all done --- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ conn.go:689 SMTP.Conn 127.0.0.1:48586: Queued from from@from to [to@localhost] - N-ULxuq44Kw -2024-05-15 23:38:42.599631 N-ULxuq44Kw from=from@from queued ip=127.0.0.1:48586 to=[to@localhost] -_ queue.go:308 Queue.SendLoop N-ULxuq44Kw: from from@from -_ queue.go:367 Queue.SendLoop N-ULxuq44Kw: testuser@localhost sent -2024-05-15 23:38:42.599752 N-ULxuq44Kw from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop N-ULxuq44Kw: all done -2024-05-15 23:38:42.599854 N-ULxuq44Kw from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48586: Queued from from@from to [to@localhost] - kSeb9bXbMV0 -2024-05-15 23:38:42.600222 kSeb9bXbMV0 from=from@from queued ip=127.0.0.1:48586 to=[to@localhost] -_ queue.go:308 Queue.SendLoop kSeb9bXbMV0: from from@from -_ queue.go:367 Queue.SendLoop kSeb9bXbMV0: testuser@localhost sent -2024-05-15 23:38:42.600296 kSeb9bXbMV0 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop kSeb9bXbMV0: all done -2024-05-15 23:38:42.600380 kSeb9bXbMV0 from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48586: Queued from from@from to [to@localhost] - kV68SM68gOA -2024-05-15 23:38:42.600620 kV68SM68gOA from=from@from queued ip=127.0.0.1:48586 to=[to@localhost] -_ queue.go:308 Queue.SendLoop kV68SM68gOA: from from@from -_ queue.go:367 Queue.SendLoop kV68SM68gOA: testuser@localhost sent -2024-05-15 23:38:42.600668 kV68SM68gOA from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop kV68SM68gOA: all done -2024-05-15 23:38:42.600743 kV68SM68gOA from=from@from all done ---- PASS: TestManyEmails (0.01s) +_ conn.go:689 SMTP.Conn 127.0.0.1:52510: Queued from from@from to [to@localhost] - ITY3veRj7Vw +2025-06-18 06:04:43.943753 ITY3veRj7Vw from=from@from queued ip=127.0.0.1:52510 to=[to@localhost] +_ queue.go:308 Queue.SendLoop ITY3veRj7Vw: from from@from +_ queue.go:367 Queue.SendLoop ITY3veRj7Vw: testuser@localhost sent +2025-06-18 06:04:43.943831 ITY3veRj7Vw from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop ITY3veRj7Vw: all done +2025-06-18 06:04:43.943923 ITY3veRj7Vw from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:52510: Queued from from@from to [to@localhost] - axaapIcEMNs +2025-06-18 06:04:43.944178 axaapIcEMNs from=from@from queued ip=127.0.0.1:52510 to=[to@localhost] +_ queue.go:308 Queue.SendLoop axaapIcEMNs: from from@from +_ queue.go:367 Queue.SendLoop axaapIcEMNs: testuser@localhost sent +2025-06-18 06:04:43.944226 axaapIcEMNs from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop axaapIcEMNs: all done +2025-06-18 06:04:43.944292 axaapIcEMNs from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:52510: Queued from from@from to [to@localhost] - biH5Wfq0Ylo +2025-06-18 06:04:43.944517 biH5Wfq0Ylo from=from@from queued ip=127.0.0.1:52510 to=[to@localhost] +_ queue.go:308 Queue.SendLoop biH5Wfq0Ylo: from from@from +_ queue.go:367 Queue.SendLoop biH5Wfq0Ylo: testuser@localhost sent +2025-06-18 06:04:43.944559 biH5Wfq0Ylo from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop biH5Wfq0Ylo: all done +2025-06-18 06:04:43.944622 biH5Wfq0Ylo from=from@from all done +--- PASS: TestManyEmails (0.02s) === RUN TestAuth -2024-05-15 23:38:42.725193 127.0.0.1:53770 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:53770: Queued from testuser@localhost to [to@localhost] - us7TQ-nJ-4w -2024-05-15 23:38:42.727887 us7TQ-nJ-4w from=testuser@localhost queued ip=127.0.0.1:53770 to=[to@localhost] -_ queue.go:308 Queue.SendLoop us7TQ-nJ-4w: from testuser@localhost -_ queue.go:367 Queue.SendLoop us7TQ-nJ-4w: testuser@localhost sent -2024-05-15 23:38:42.727970 us7TQ-nJ-4w from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop us7TQ-nJ-4w: all done -2024-05-15 23:38:42.728064 us7TQ-nJ-4w from=testuser@localhost all done ---- PASS: TestAuth (0.13s) +2025-06-18 06:04:44.121641 127.0.0.1:45968 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:45968: Queued from testuser@localhost to [to@localhost] - 8-KYDBJTZ08 +2025-06-18 06:04:44.122258 8-KYDBJTZ08 from=testuser@localhost queued ip=127.0.0.1:45968 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 8-KYDBJTZ08: from testuser@localhost +_ queue.go:367 Queue.SendLoop 8-KYDBJTZ08: testuser@localhost sent +2025-06-18 06:04:44.122329 8-KYDBJTZ08 from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 8-KYDBJTZ08: all done +2025-06-18 06:04:44.122425 8-KYDBJTZ08 from=testuser@localhost all done +--- PASS: TestAuth (0.18s) === RUN TestSubmissionWithoutAuth -_ conn.go:297 SMTP.Conn 127.0.0.1:53772: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ conn.go:297 SMTP.Conn 127.0.0.1:45978: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.00s) === RUN TestAuthOnTLS -2024-05-15 23:38:42.861931 127.0.0.1:59358 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:59358: Queued from testuser@localhost to [to@localhost] - OaGj0XbMDJM -2024-05-15 23:38:42.862606 OaGj0XbMDJM from=testuser@localhost queued ip=127.0.0.1:59358 to=[to@localhost] -_ queue.go:308 Queue.SendLoop OaGj0XbMDJM: from testuser@localhost -_ queue.go:367 Queue.SendLoop OaGj0XbMDJM: testuser@localhost sent -2024-05-15 23:38:42.862692 OaGj0XbMDJM from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop OaGj0XbMDJM: all done -2024-05-15 23:38:42.862807 OaGj0XbMDJM from=testuser@localhost all done ---- PASS: TestAuthOnTLS (0.13s) +2025-06-18 06:04:44.246191 127.0.0.1:60788 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:60788: Queued from testuser@localhost to [to@localhost] - wbMn3UG3nHc +2025-06-18 06:04:44.246753 wbMn3UG3nHc from=testuser@localhost queued ip=127.0.0.1:60788 to=[to@localhost] +_ queue.go:308 Queue.SendLoop wbMn3UG3nHc: from testuser@localhost +_ queue.go:367 Queue.SendLoop wbMn3UG3nHc: testuser@localhost sent +2025-06-18 06:04:44.246827 wbMn3UG3nHc from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop wbMn3UG3nHc: all done +2025-06-18 06:04:44.246929 wbMn3UG3nHc from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.12s) === RUN TestAuthOnSMTP -2024-05-15 23:38:42.991696 127.0.0.1:48600 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:48600: Queued from testuser@localhost to [to@localhost] - 3aHVpJ0SXhg -2024-05-15 23:38:42.992210 3aHVpJ0SXhg from=testuser@localhost queued ip=127.0.0.1:48600 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 3aHVpJ0SXhg: from testuser@localhost -_ queue.go:367 Queue.SendLoop 3aHVpJ0SXhg: testuser@localhost sent -2024-05-15 23:38:42.992280 3aHVpJ0SXhg from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 3aHVpJ0SXhg: all done -2024-05-15 23:38:42.992376 3aHVpJ0SXhg from=testuser@localhost all done ---- PASS: TestAuthOnSMTP (0.13s) +2025-06-18 06:04:44.368996 127.0.0.1:52524 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:52524: Queued from testuser@localhost to [to@localhost] - GbFb3tPX86I +2025-06-18 06:04:44.369563 GbFb3tPX86I from=testuser@localhost queued ip=127.0.0.1:52524 to=[to@localhost] +_ queue.go:308 Queue.SendLoop GbFb3tPX86I: from testuser@localhost +_ queue.go:367 Queue.SendLoop GbFb3tPX86I: testuser@localhost sent +2025-06-18 06:04:44.369629 GbFb3tPX86I from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop GbFb3tPX86I: all done +2025-06-18 06:04:44.369741 GbFb3tPX86I from=testuser@localhost all done +--- PASS: TestAuthOnSMTP (0.12s) === RUN TestBrokenAuth -_ conn.go:1062 SMTP.Conn 127.0.0.1:53784: error: error authenticating \"user\"@\"broken\": failed to auth -2024-05-15 23:38:43.111460 127.0.0.1:53784 auth failed for user@broken -_ conn.go:297 SMTP.Conn 127.0.0.1:53784: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:297 SMTP.Conn 127.0.0.1:53784: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +_ conn.go:1062 SMTP.Conn 127.0.0.1:45992: error: error authenticating \"user\"@\"broken\": failed to auth +2025-06-18 06:04:44.491397 127.0.0.1:45992 auth failed for user@broken +_ conn.go:297 SMTP.Conn 127.0.0.1:45992: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:297 SMTP.Conn 127.0.0.1:45992: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command --- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:297 SMTP.Conn 127.0.0.1:48608: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48608: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48608: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48608: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48612: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48612: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48612: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48612: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48614: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48614: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48614: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48614: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48626: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48626: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48626: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48626: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48634: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48634: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48634: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48634: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48644: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48644: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48644: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48644: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52530: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52530: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52530: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52530: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52542: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52542: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52542: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52542: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52546: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52546: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52546: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52546: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52548: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52548: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52548: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52548: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52554: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52554: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52554: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52554: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52556: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:52556: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:52556: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52556: error: too many errors, breaking connection --- PASS: TestWrongMailParsing (0.00s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:297 SMTP.Conn 127.0.0.1:48668: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:297 SMTP.Conn 127.0.0.1:52570: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption ---- PASS: TestRcptOption (0.01s) +--- PASS: TestRcptOption (0.00s) === RUN TestRelayForbidden -2024-05-15 23:38:43.124823 127.0.0.1:48692 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48692: error: RCPT failed: 503 5.7.1 Relay not allowed +2025-06-18 06:04:44.496796 127.0.0.1:52588 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52588: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2024-05-15 23:38:43.255713 127.0.0.1:53800 auth succeeded for testuser@localhost -_ conn.go:297 SMTP.Conn 127.0.0.1:53800: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.14s) +2025-06-18 06:04:44.656195 127.0.0.1:46002 auth succeeded for testuser@localhost +_ conn.go:297 SMTP.Conn 127.0.0.1:46002: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.16s) === RUN TestRcptBrokenExists -_ conn.go:601 SMTP.Conn 127.0.0.1:48694: error: error checking if user \"to@broken\" exists: failed to check if user exists -2024-05-15 23:38:43.272522 127.0.0.1:48694 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:48694: error: RCPT failed: 451 4.4.3 Temporary error checking address ---- PASS: TestRcptBrokenExists (0.01s) +_ conn.go:601 SMTP.Conn 127.0.0.1:52598: error: error checking if user \"to@broken\" exists: failed to check if user exists +2025-06-18 06:04:44.662157 127.0.0.1:52598 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:52598: error: RCPT failed: 451 4.4.3 Temporary error checking address +--- PASS: TestRcptBrokenExists (0.00s) === RUN TestRcptUserDoesNotExist -2024-05-15 23:38:43.274866 127.0.0.1:48698 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:297 SMTP.Conn 127.0.0.1:48698: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) ---- PASS: TestRcptUserDoesNotExist (0.00s) +2025-06-18 06:04:44.668810 127.0.0.1:52612 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:297 SMTP.Conn 127.0.0.1:52612: 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:48706: Queued from from@from to [to@localhost] - iFw4ZYr-JXs -2024-05-15 23:38:43.449844 iFw4ZYr-JXs from=from@from queued ip=127.0.0.1:48706 to=[to@localhost] -_ queue.go:308 Queue.SendLoop iFw4ZYr-JXs: from from@from -_ queue.go:367 Queue.SendLoop iFw4ZYr-JXs: testuser@localhost sent -2024-05-15 23:38:43.449987 iFw4ZYr-JXs from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop iFw4ZYr-JXs: all done -2024-05-15 23:38:43.514596 iFw4ZYr-JXs from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48706: Queued from from@from to [to@localhost] - iyiSeRaXb-w -2024-05-15 23:38:43.627532 iyiSeRaXb-w from=from@from queued ip=127.0.0.1:48706 to=[to@localhost] -_ queue.go:308 Queue.SendLoop iyiSeRaXb-w: from from@from -_ queue.go:367 Queue.SendLoop iyiSeRaXb-w: testuser@localhost sent -2024-05-15 23:38:43.627663 iyiSeRaXb-w from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop iyiSeRaXb-w: all done -2024-05-15 23:38:43.656266 iyiSeRaXb-w from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:48706: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:689 SMTP.Conn 127.0.0.1:48706: Queued from from@from to [to@localhost] - c9R_gK3ssEc -2024-05-15 23:38:43.996451 c9R_gK3ssEc from=from@from queued ip=127.0.0.1:48706 to=[to@localhost] -_ queue.go:308 Queue.SendLoop c9R_gK3ssEc: from from@from -_ queue.go:367 Queue.SendLoop c9R_gK3ssEc: testuser@localhost sent -2024-05-15 23:38:43.996573 c9R_gK3ssEc from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.74s) +_ conn.go:689 SMTP.Conn 127.0.0.1:52622: Queued from from@from to [to@localhost] - WB5_kKUePx4 +2025-06-18 06:04:44.844091 WB5_kKUePx4 from=from@from queued ip=127.0.0.1:52622 to=[to@localhost] +_ queue.go:308 Queue.SendLoop WB5_kKUePx4: from from@from +_ queue.go:367 Queue.SendLoop WB5_kKUePx4: testuser@localhost sent +2025-06-18 06:04:44.844226 WB5_kKUePx4 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop WB5_kKUePx4: all done +2025-06-18 06:04:44.883921 WB5_kKUePx4 from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:52622: Queued from from@from to [to@localhost] - WJ2vl4Nfwa8 +2025-06-18 06:04:45.063860 WJ2vl4Nfwa8 from=from@from queued ip=127.0.0.1:52622 to=[to@localhost] +_ queue.go:308 Queue.SendLoop WJ2vl4Nfwa8: from from@from +_ queue.go:367 Queue.SendLoop WJ2vl4Nfwa8: testuser@localhost sent +2025-06-18 06:04:45.067515 WJ2vl4Nfwa8 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop WJ2vl4Nfwa8: all done +2025-06-18 06:04:45.101683 WJ2vl4Nfwa8 from=from@from all done +_ conn.go:297 SMTP.Conn 127.0.0.1:52622: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:689 SMTP.Conn 127.0.0.1:52622: Queued from from@from to [to@localhost] - gJQ9uYOj-jY +2025-06-18 06:04:45.419773 gJQ9uYOj-jY from=from@from queued ip=127.0.0.1:52622 to=[to@localhost] +_ queue.go:308 Queue.SendLoop gJQ9uYOj-jY: from from@from +_ queue.go:367 Queue.SendLoop gJQ9uYOj-jY: testuser@localhost sent +2025-06-18 06:04:45.419902 gJQ9uYOj-jY from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.76s) === RUN TestSimpleCommands -_ conn.go:297 SMTP.Conn 127.0.0.1:48712: 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:48712: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. ---- PASS: TestSimpleCommands (0.00s) +_ conn.go:297 SMTP.Conn 127.0.0.1:52626: 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:52626: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +--- PASS: TestSimpleCommands (0.02s) === RUN TestLongLines -_ conn.go:297 SMTP.Conn 127.0.0.1:48728: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:327 SMTP.Conn 127.0.0.1:48728: error: exiting with error: line too long +_ conn.go:297 SMTP.Conn 127.0.0.1:52634: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:327 SMTP.Conn 127.0.0.1:52634: error: exiting with error: line too long --- PASS: TestLongLines (0.00s) === RUN TestReset --- PASS: TestReset (0.00s) === RUN TestRepeatedStartTLS -_ queue.go:342 Queue.SendLoop c9R_gK3ssEc: all done -2024-05-15 23:38:44.033958 c9R_gK3ssEc from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:48750: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:297 SMTP.Conn 127.0.0.1:52648: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestRepeatedStartTLS (0.00s) === RUN TestStartTLSOnTLS -_ conn.go:297 SMTP.Conn 127.0.0.1:59370: error: STARTTLS failed: 503 5.5.1 You are already wearing that! ---- PASS: TestStartTLSOnTLS (0.01s) +_ queue.go:342 Queue.SendLoop gJQ9uYOj-jY: all done +2025-06-18 06:04:45.459997 gJQ9uYOj-jY from=from@from all done +_ conn.go:297 SMTP.Conn 127.0.0.1:60796: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +--- PASS: TestStartTLSOnTLS (0.02s) === RUN FuzzConnection === RUN FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 -_ conn.go:297 SMTP.Conn 127.0.0.1:48764: error: RCPT failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:48764: error: RCPT failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:48764: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48764: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52662: error: RCPT failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:52662: error: RCPT failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:52662: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:52662: error: too many errors, breaking connection === RUN FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 -_ conn.go:297 SMTP.Conn 127.0.0.1:48772: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:48772: error: DATA failed: 503 5.5.1 Sender not yet given -2024-05-15 23:38:44.051777 127.0.0.1:48772 rejected from=a@b to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48772: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:304 SMTP.Conn 127.0.0.1:48772: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52672: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:52672: error: DATA failed: 503 5.5.1 Sender not yet given +2025-06-18 06:04:45.462893 127.0.0.1:52672 rejected from=a@b to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52672: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:304 SMTP.Conn 127.0.0.1:52672: error: too many errors, breaking connection === RUN FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f -2024-05-15 23:38:44.052209 127.0.0.1:48778 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48778: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48778: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:48778: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:48778: error: too many errors, breaking connection +2025-06-18 06:04:45.463146 127.0.0.1:52684 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52684: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52684: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:52684: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:52684: error: too many errors, breaking connection === RUN FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe -_ conn.go:297 SMTP.Conn 127.0.0.1:48792: error: MAIL failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:48792: error: MAIL failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:48792: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:304 SMTP.Conn 127.0.0.1:48792: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52700: error: MAIL failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:52700: error: MAIL failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:52700: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:304 SMTP.Conn 127.0.0.1:52700: error: too many errors, breaking connection === RUN FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 -_ conn.go:297 SMTP.Conn 127.0.0.1:48804: error: HELO failed: 501 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:48804: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:52704: error: HELO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:52704: error: EHLO failed: 501 Invisible customers are not welcome! === RUN FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf === RUN FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 -2024-05-15 23:38:44.053300 127.0.0.1:48814 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48814: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48814: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:48814: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:48814: error: too many errors, breaking connection +2025-06-18 06:04:45.464603 127.0.0.1:52720 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52720: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:52720: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:52720: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:52720: error: too many errors, breaking connection === RUN FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 -_ conn.go:297 SMTP.Conn 127.0.0.1:48826: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:48826: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:48826: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:304 SMTP.Conn 127.0.0.1:48826: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52726: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:52726: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:52726: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:304 SMTP.Conn 127.0.0.1:52726: error: too many errors, breaking connection === RUN FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded === RUN FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 -_ conn.go:297 SMTP.Conn 127.0.0.1:48842: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:52742: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command === RUN FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c -_ conn.go:297 SMTP.Conn 127.0.0.1:48854: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:59386: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage -_ conn.go:297 SMTP.Conn 127.0.0.1:59386: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 -2024-05-15 23:38:44.171737 127.0.0.1:59386 auth failed for user@testserver -_ conn.go:297 SMTP.Conn 127.0.0.1:59386: error: AUTH failed: 535 5.7.8 Incorrect user or password -_ conn.go:304 SMTP.Conn 127.0.0.1:59386: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:52758: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:60812: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage +_ conn.go:297 SMTP.Conn 127.0.0.1:60812: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 +2025-06-18 06:04:45.587347 127.0.0.1:60812 auth failed for user@testserver +_ conn.go:297 SMTP.Conn 127.0.0.1:60812: error: AUTH failed: 535 5.7.8 Incorrect user or password +_ conn.go:304 SMTP.Conn 127.0.0.1:60812: error: too many errors, breaking connection --- PASS: FuzzConnection (0.13s) --- 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) @@ -1568,14 +1604,14 @@ --- PASS: FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 (0.00s) --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.12s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.014s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.104s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) === RUN TestCheckPolicy --- PASS: TestCheckPolicy (0.00s) === RUN TestMatchDomain ---- PASS: TestMatchDomain (0.01s) +--- PASS: TestMatchDomain (0.00s) === RUN TestMXIsAllowed --- PASS: TestMXIsAllowed (0.00s) === RUN TestFetch @@ -1586,16 +1622,16 @@ --- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing ---- PASS: TestPolicyTooBig (0.15s) +--- PASS: TestPolicyTooBig (0.11s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_2498744673" + testlib.go:32: test directory: "/tmp/testlib_3765810493" sts_test.go:277: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:286: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:300: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:309: cache fetched domErr: --- PASS: TestCacheBasics (0.00s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_3380553675" + testlib.go:32: test directory: "/tmp/testlib_3243907677" 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 +1640,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_227664138" + testlib.go:32: test directory: "/tmp/testlib_669584538" 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_2246884283" + testlib.go:32: test directory: "/tmp/testlib_3505620202" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1618,11 +1654,11 @@ === RUN TestHasSTSRecord --- PASS: TestHasSTSRecord (0.00s) === RUN TestHTTPGet ---- PASS: TestHTTPGet (0.01s) +--- PASS: TestHTTPGet (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.232s +ok blitiri.com.ar/go/chasquid/internal/sts 0.182s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_2314111556" + testlib.go:32: test directory: "/tmp/testlib_1987534400" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -1633,54 +1669,54 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_345674309" + testlib.go:32: test directory: "/tmp/testlib_4036356730" --- PASS: TestRewrite (0.00s) === RUN TestGetFreePort --- PASS: TestGetFreePort (0.00s) === RUN TestWaitFor ---- PASS: TestWaitFor (0.03s) +--- PASS: TestWaitFor (0.02s) === RUN TestGenerateCert - testlib.go:32: test directory: "/tmp/testlib_583942657" ---- PASS: TestGenerateCert (0.08s) + testlib.go:32: test directory: "/tmp/testlib_2309394324" +--- PASS: TestGenerateCert (0.18s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.09s) +--- PASS: TestGenerateCertBadDir (0.12s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.326s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.369s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.047s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.044s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test1693114271" - userdb_test.go:37: file: "/tmp/userdb_test4033878547" + userdb_test.go:37: file: "/tmp/userdb_test1862835724" + userdb_test.go:37: file: "/tmp/userdb_test3802466272" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test721139445" ---- PASS: TestWrite (0.88s) + userdb_test.go:37: file: "/tmp/userdb_test2223774180" +--- PASS: TestWrite (1.19s) === RUN TestNew ---- PASS: TestNew (0.10s) +--- PASS: TestNew (0.17s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test790293027" + userdb_test.go:37: file: "/tmp/userdb_test4205132291" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test4073766750" + userdb_test.go:37: file: "/tmp/userdb_test2722321715" --- PASS: TestPlainScheme (0.00s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test3977637401" + userdb_test.go:37: file: "/tmp/userdb_test1196040366" --- PASS: TestDeniedScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test2938898383" + userdb_test.go:37: file: "/tmp/userdb_test3404490010" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test1889600916" ---- PASS: TestRemoveUser (0.11s) + userdb_test.go:37: file: "/tmp/userdb_test3089967867" +--- PASS: TestRemoveUser (0.18s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test3403001560" ---- PASS: TestExists (0.15s) + userdb_test.go:37: file: "/tmp/userdb_test4183918685" +--- PASS: TestExists (0.17s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.288s +ok blitiri.com.ar/go/chasquid/internal/userdb 1.741s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1734,8 +1770,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' in '../chasquid_1.13-1_arm64.deb'. 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-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 +1779,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/3692366/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/3692366/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/2199066 and its subdirectories -I: Current time: Wed May 15 11:39:20 -12 2024 -I: pbuilder-time-stamp: 1715816360 +I: removing directory /srv/workspace/pbuilder/3692366 and its subdirectories +I: Current time: Wed Jun 18 20:05:35 +14 2025 +I: pbuilder-time-stamp: 1750226735