Diff of the two buildlogs: -- --- b1/build.log 2024-05-09 10:24:22.835968367 +0000 +++ b2/build.log 2024-05-09 10:26:57.089402170 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed May 8 22:22:02 -12 2024 -I: pbuilder-time-stamp: 1715250122 +I: Current time: Thu Jun 12 06:47:25 +14 2025 +I: pbuilder-time-stamp: 1749660445 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -27,51 +27,83 @@ 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/2269354/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/3063882/tmp/hooks/D01_modify_environment starting +debug: Running on infom02-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jun 11 16:47 /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/3063882/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/3063882/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='amd64' - DEBIAN_FRONTEND='noninteractive' + 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]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=12 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='amd64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='cb35ecd2c8554d5d8cb277d57582e6a9' - 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='2269354' - PS1='# ' - PS2='> ' + INVOCATION_ID=96287ce73add4def9102242b25eb51e7 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=3063882 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.ENiv95JY/pbuilderrc_iSip --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.ENiv95JY/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' + 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.ENiv95JY/pbuilderrc_JR1t --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.ENiv95JY/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' I: uname -a - Linux infom01-amd64 6.1.0-21-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.6.13+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.6.13-1~bpo12+1 (2024-02-15) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 8 11:24 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2269354/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 10 17:51 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/3063882/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -175,7 +207,7 @@ Get: 44 http://deb.debian.org/debian trixie/main amd64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 45 http://deb.debian.org/debian trixie/main amd64 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 46 http://deb.debian.org/debian trixie/main amd64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 70.4 MB in 1s (114 MB/s) +Fetched 70.4 MB in 1s (137 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 ... 19699 files and directories currently installed.) @@ -374,7 +406,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/3063882/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/3063882/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 @@ -395,32 +431,32 @@ 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-x86_64-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/unsafeheader -internal/goexperiment -internal/goarch +internal/godebugs encoding -unicode/utf8 internal/coverage/rtcov -internal/godebugs -internal/goos -math/bits +internal/unsafeheader internal/itoa -internal/race -runtime/internal/math -runtime/internal/sys +math/bits +internal/goarch +internal/goos +runtime/internal/syscall +unicode/utf8 internal/cpu +internal/goexperiment runtime/internal/atomic +internal/race +sync/atomic unicode cmp -runtime/internal/syscall log/internal -sync/atomic internal/abi -internal/nettrace internal/chacha8rand +runtime/internal/math +runtime/internal/sys +internal/nettrace +slices google.golang.org/protobuf/internal/flags unicode/utf16 -slices google.golang.org/protobuf/internal/set container/ring container/list @@ -437,98 +473,97 @@ sync internal/testlog internal/bisect -internal/singleflight google.golang.org/protobuf/internal/pragma +internal/singleflight runtime/cgo internal/godebug errors sort +internal/intern +math/rand strconv +vendor/golang.org/x/net/dns/dnsmessage +reflect +net/netip +golang.org/x/text/internal/tag +crypto/rc4 +path +internal/safefilepath io -math/rand internal/oserror -internal/intern -syscall -path crypto/internal/nistec/fiat -golang.org/x/text/internal/tag -vendor/golang.org/x/net/dns/dnsmessage -internal/safefilepath -strings -hash -bytes +syscall crypto/internal/randutil +bytes text/tabwriter -hash/fnv +hash +strings hash/crc32 -reflect -net/netip crypto -crypto/rc4 -vendor/golang.org/x/text/transform -golang.org/x/text/transform -bufio +hash/fnv html +bufio net/http/internal/ascii +vendor/golang.org/x/text/transform +golang.org/x/text/transform regexp/syntax golang.org/x/text/runes golang.org/x/text/width regexp internal/syscall/unix -internal/syscall/execenv time +internal/syscall/execenv +internal/fmtsort +encoding/binary internal/poll io/fs context embed -internal/fmtsort -encoding/binary -os google.golang.org/protobuf/internal/editiondefaults encoding/base64 crypto/cipher crypto/internal/edwards25519/field -crypto/internal/nistec crypto/md5 +crypto/internal/nistec vendor/golang.org/x/crypto/internal/poly1305 golang.org/x/sys/unix +os encoding/pem crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 -crypto/internal/edwards25519 crypto/aes crypto/sha512 crypto/hmac crypto/sha1 +crypto/internal/edwards25519 crypto/sha256 +vendor/golang.org/x/crypto/hkdf +golang.org/x/crypto/pbkdf2 +crypto/ecdh +golang.org/x/crypto/scrypt fmt -path/filepath google.golang.org/protobuf/internal/detrand -io/ioutil +path/filepath blitiri.com.ar/go/chasquid/internal/safeio +internal/sysinfo +io/ioutil vendor/golang.org/x/sys/cpu -vendor/golang.org/x/crypto/hkdf -golang.org/x/crypto/pbkdf2 -crypto/ecdh internal/lazyregexp os/signal os/exec -internal/sysinfo vendor/golang.org/x/crypto/chacha20poly1305 -golang.org/x/crypto/scrypt -net -flag log google.golang.org/protobuf/internal/errors go/token compress/flate -encoding/json google.golang.org/protobuf/internal/version net/url text/template/parse -math/big +flag +encoding/json google.golang.org/protobuf/encoding/protowire +math/big encoding/hex vendor/golang.org/x/text/unicode/bidi google.golang.org/protobuf/reflect/protoreflect @@ -541,12 +576,13 @@ blitiri.com.ar/go/chasquid/internal/envelope golang.org/x/text/unicode/bidi golang.org/x/text/unicode/norm -golang.org/x/text/internal/language -text/template vendor/golang.org/x/text/secure/bidirule +text/template +golang.org/x/text/internal/language blitiri.com.ar/go/chasquid/internal/tlsconst internal/profile runtime/pprof +runtime/trace google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/genid @@ -556,77 +592,77 @@ google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts +vendor/golang.org/x/net/idna golang.org/x/text/secure/bidirule golang.org/x/text/internal/language/compact +runtime/debug +golang.org/x/text/language +golang.org/x/term +net google.golang.org/protobuf/proto -runtime/trace +google.golang.org/protobuf/internal/encoding/defval +html/template +testing crypto/rand crypto/elliptic -html/template -crypto/internal/bigmod crypto/internal/boring/bbig +crypto/internal/bigmod encoding/asn1 crypto/dsa -google.golang.org/protobuf/internal/encoding/defval -crypto/ed25519 golang.org/x/net/idna -golang.org/x/text/language -vendor/golang.org/x/net/idna -runtime/debug -golang.org/x/term -crypto/rsa +golang.org/x/text/internal +golang.org/x/text/cases +crypto/ed25519 google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix -testing -golang.org/x/text/internal -golang.org/x/text/cases -crypto/ecdsa -blitiri.com.ar/go/chasquid/internal/protoio +crypto/rsa golang.org/x/text/secure/precis +blitiri.com.ar/go/chasquid/internal/protoio +crypto/ecdsa +blitiri.com.ar/go/chasquid/internal/normalize google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/internal/impl -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 vendor/golang.org/x/net/http/httpproxy -blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/spf +blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/systemd +crypto/x509 blitiri.com.ar/go/log -vendor/golang.org/x/net/http/httpguts -mime/multipart blitiri.com.ar/go/chasquid/internal/dovecot +mime/multipart net/mail blitiri.com.ar/go/chasquid/cmd/mda-lmtp +vendor/golang.org/x/net/http/httpguts blitiri.com.ar/go/chasquid/internal/config crypto/tls -net/http/httptrace net/smtp +net/http/httptrace blitiri.com.ar/go/chasquid/internal/testlib net/http blitiri.com.ar/go/chasquid/internal/smtp expvar -blitiri.com.ar/go/chasquid/internal/nettrace 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/sts +blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/localrpc blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/auth +blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/cmd/chasquid-util -blitiri.com.ar/go/chasquid/internal/courier blitiri.com.ar/go/chasquid/cmd/smtp-check +blitiri.com.ar/go/chasquid/internal/courier blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid @@ -660,13 +696,13 @@ === 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.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.100s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.031s === 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.47s) +--- PASS: TestAuthenticate (0.40s) === 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,31 +764,32 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 0.705s +ok blitiri.com.ar/go/chasquid/internal/auth 0.600s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_3430721141" + testlib.go:32: test directory: "/tmp/testlib_2500479867" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_805410253" + testlib.go:32: test directory: "/tmp/testlib_177094840" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_3662139899" + testlib.go:32: test directory: "/tmp/testlib_2580352764" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_1585755744" + testlib.go:32: test directory: "/tmp/testlib_1103742366" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.044s +ok blitiri.com.ar/go/chasquid/internal/config 0.012s +? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_1578083401" ---- PASS: TestMDA (0.03s) + testlib.go:32: test directory: "/tmp/testlib_1274201820" +--- 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\" @@ -763,10 +800,10 @@ === RUN TestSanitize --- PASS: TestSanitize (0.00s) === RUN TestSMTP - testlib.go:32: test directory: "/tmp/testlib_2814767863" - testlib.go:32: test directory: "/tmp/testlib_2044177260" -_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::42657: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::42657: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_1283380251" + testlib.go:32: test directory: "/tmp/testlib_42019178" +_ smtp.go:127 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::43075: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::43075: 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:" @@ -774,26 +811,26 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestSMTP (0.03s) +--- PASS: TestSMTP (0.05s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_2416667867" - testlib.go:32: test directory: "/tmp/testlib_4233220658" + testlib.go:32: test directory: "/tmp/testlib_1398924582" + testlib.go:32: test directory: "/tmp/testlib_4213335107" fakeserver_test.go:98: fakeServer got connection -_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:49382->127.0.0.1:35033: 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:49382->127.0.0.1:35033: 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:49382->127.0.0.1:35033: 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:49382->127.0.0.1:35033: i/o timeout) +_ smtp.go:138 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:37996->127.0.0.1:37331: 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:37996->127.0.0.1:37331: 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:37996->127.0.0.1:37331: 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:37996->127.0.0.1:37331: i/o timeout) fakeserver_test.go:105: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_692118369" - testlib.go:32: test directory: "/tmp/testlib_37272341" + testlib.go:32: test directory: "/tmp/testlib_2098569419" + testlib.go:32: test directory: "/tmp/testlib_2802007811" 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_770233805" - testlib.go:32: test directory: "/tmp/testlib_339294744" + testlib.go:32: test directory: "/tmp/testlib_576648036" + testlib.go:32: test directory: "/tmp/testlib_3666126572" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -801,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_3310585949" - testlib.go:32: test directory: "/tmp/testlib_1270560190" + testlib.go:32: test directory: "/tmp/testlib_1848018373" + testlib.go:32: test directory: "/tmp/testlib_1971092212" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "MAIL FROM:" @@ -811,19 +848,19 @@ _ 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_3993511414" - testlib.go:32: test directory: "/tmp/testlib_747637945" + testlib.go:32: test directory: "/tmp/testlib_510357691" + testlib.go:32: test directory: "/tmp/testlib_4041217845" 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" _ 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.18s) + smtp_test.go:140: failed as expected: DATA closing 551 data response error +--- PASS: TestSMTPErrors (1.33s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_1998931579" + testlib.go:32: test directory: "/tmp/testlib_2344720540" _ 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) @@ -838,8 +875,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_7326084" - testlib.go:32: test directory: "/tmp/testlib_2188730972" + testlib.go:32: test directory: "/tmp/testlib_3450581878" + testlib.go:32: test directory: "/tmp/testlib_3086090913" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -850,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_1609759431" + testlib.go:32: test directory: "/tmp/testlib_3348674635" 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 @@ -858,10 +895,10 @@ _ 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.07s) +--- PASS: TestTLS (0.06s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_3470749902" - testlib.go:32: test directory: "/tmp/testlib_3992484016" + testlib.go:32: test directory: "/tmp/testlib_2783189042" + testlib.go:32: test directory: "/tmp/testlib_466526937" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -874,10 +911,10 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestTLSError (0.02s) +--- PASS: TestTLSError (0.03s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_4056517" - testlib.go:32: test directory: "/tmp/testlib_3829014784" + testlib.go:32: test directory: "/tmp/testlib_4110305349" + testlib.go:32: test directory: "/tmp/testlib_1444938169" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -885,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_3528621643" + testlib.go:32: test directory: "/tmp/testlib_2656056966" fakeserver_test.go:98: fakeServer got connection fakeserver_test.go:109: fakeServer read: "EHLO hello" fakeserver_test.go:109: fakeServer read: "STARTTLS" @@ -896,27 +933,27 @@ fakeserver_test.go:109: fakeServer read: "DATA" fakeserver_test.go:109: fakeServer read: "QUIT" fakeserver_test.go:105: fakeServer exiting: EOF ---- PASS: TestSTSPolicyEnforcement (0.19s) +--- PASS: TestSTSPolicyEnforcement (0.10s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 1.669s +ok blitiri.com.ar/go/chasquid/internal/courier 1.694s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_367172132" + testlib.go:32: test directory: "/tmp/testlib_1466330219" _ domaininfo.go:117 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:109 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:153 DomainInfo.Outgoing d1: d1 outgoing level raised: TLS_SECURE > PLAIN _ domaininfo.go:145 DomainInfo.Outgoing d1: error: d1 outgoing denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:109 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:182 DomainInfo.SetToPlain d1: set to plain ---- PASS: TestBasic (0.53s) +--- PASS: TestBasic (0.04s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_3769436100" + testlib.go:32: test directory: "/tmp/testlib_1727468414" _ 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.28s) +--- PASS: TestNewDomain (0.06s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_3633899122" + testlib.go:32: test directory: "/tmp/testlib_1068937069" _ 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 @@ -929,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.03s) +--- PASS: TestProgressions (0.02s) === RUN TestErrors - testlib.go:32: test directory: "/tmp/testlib_2542870597" + testlib.go:32: test directory: "/tmp/testlib_3625843011" _ 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.01s) === RUN TestDirectoryErrors - testlib.go:32: test directory: "/tmp/testlib_4126453622" -_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_4126453622/db: no such file or directory -_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_4126453622/db/.s:d12112518560: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_59239895" +_ domaininfo.go:59 DomainInfo.Reload reload: error: open /tmp/testlib_59239895/db: no such file or directory +_ domaininfo.go:84 DomainInfo.write d1: error: open /tmp/testlib_59239895/db/.s:d13917885058: no such file or directory --- PASS: TestDirectoryErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.884s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.146s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_1871594543" + testlib.go:32: test directory: "/tmp/testlib_2118524779" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.048s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.008s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -959,13 +996,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.032s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.004s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.004s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.019s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -990,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.007s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.018s === RUN TestBadServer - asm_amd64.s:1695: FakeServer &{{0xc0000c5180}}: accepted - asm_amd64.s:1695: FakeServer &{{0xc0000c5180}}: readRequest: "" "" / EOF - asm_amd64.s:1695: FakeServer &{{0xc0000c5180}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-158630850/rpc.sock->@: write: broken pipe - asm_amd64.s:1695: FakeServer &{{0xc0000c5180}}: closing - asm_amd64.s:1695: FakeServer &{{0xc0000c5300}}: accepted - asm_amd64.s:1695: FakeServer &{{0xc0000c5300}}: readRequest: "Echo" "" / - asm_amd64.s:1695: FakeServer &{{0xc0000c5300}}: writeMessage("xxx"): 3 - asm_amd64.s:1695: FakeServer &{{0xc0000c5300}}: closing + asm_amd64.s:1695: FakeServer &{{0xc0000c4d80}}: accepted + asm_amd64.s:1695: FakeServer &{{0xc0000c4d80}}: readRequest: "" "" / EOF + asm_amd64.s:1695: FakeServer &{{0xc0000c4d80}}: writeMessage("xxx"): 0 write unix /tmp/rpc-test-2524408881/rpc.sock->@: write: broken pipe + asm_amd64.s:1695: FakeServer &{{0xc0000c4d80}}: closing + asm_amd64.s:1695: FakeServer &{{0xc0000c4f00}}: accepted + asm_amd64.s:1695: FakeServer &{{0xc0000c4f00}}: readRequest: "Echo" "" / + asm_amd64.s:1695: FakeServer &{{0xc0000c4f00}}: writeMessage("xxx"): 3 + asm_amd64.s:1695: FakeServer &{{0xc0000c4f00}}: closing --- PASS: TestBadServer (0.01s) === RUN TestBadSocket --- PASS: TestBadSocket (0.00s) === RUN TestEndToEnd -_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-493636439/sock: Listening +_ localrpc.go:63 LocalRPC.Server /tmp/rpc-test-2931597937/sock: Listening === RUN TestEndToEnd/Echo === RUN TestEndToEnd/Echo#01 === RUN TestEndToEnd/Hola @@ -1012,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-493636439/sock: error: Accept error: accept unix /tmp/rpc-test-493636439/sock: use of closed network connection +_ localrpc.go:67 LocalRPC.Server /tmp/rpc-test-2931597937/sock: error: Accept error: accept unix /tmp/rpc-test-2931597937/sock: use of closed network connection --- PASS: TestEndToEnd (0.01s) --- PASS: TestEndToEnd/Echo (0.00s) --- PASS: TestEndToEnd/Echo#01 (0.00s) @@ -1027,7 +1064,7 @@ === RUN TestShortReadRequest --- PASS: TestShortReadRequest (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/localrpc 0.041s +ok blitiri.com.ar/go/chasquid/internal/localrpc 0.031s === RUN TestLogger --- PASS: TestLogger (0.00s) === RUN TestDefault @@ -1035,8 +1072,7 @@ === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.007s -? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] +ok blitiri.com.ar/go/chasquid/internal/maillog 0.006s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -1046,11 +1082,11 @@ === RUN TestHTTP --- PASS: TestHTTP (0.01s) === RUN TestHTTPLong ---- PASS: TestHTTPLong (0.00s) +--- PASS: TestHTTPLong (0.01s) === RUN TestHTTPErrors --- PASS: TestHTTPErrors (0.01s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.00s) +--- PASS: TestHTTPUroboro (0.01s) === RUN TestHTTPDeep --- PASS: TestHTTPDeep (0.00s) === RUN TestStripZeros @@ -1072,7 +1108,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.037s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.066s === 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.004s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.022s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_3553396358" + testlib.go:32: test directory: "/tmp/testlib_606380687" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_1791577335" ---- PASS: TestText (0.00s) + testlib.go:32: test directory: "/tmp/testlib_1638532670" +--- PASS: TestText (0.01s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_2515323221" + testlib.go:32: test directory: "/tmp/testlib_236621185" --- PASS: TestStore (0.00s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_1621073124" + testlib.go:32: test directory: "/tmp/testlib_1038340708" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_161917678" + testlib.go:32: test directory: "/tmp/testlib_2860741087" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.007s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.030s === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Thu, 09 May 2024 10:24:00 +0000 + Message-ID: + Date: Wed, 11 Jun 2025 16:49:31 +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="5ysVjo5_4q8" + boundary="xI6KtaQs2a4" - --5ysVjo5_4q8 + --xI6KtaQs2a4 Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1190,7 +1226,7 @@ oh! the humanity! - --5ysVjo5_4q8 + --xI6KtaQs2a4 Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1221,7 +1257,7 @@ - --5ysVjo5_4q8 + --xI6KtaQs2a4 Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1231,73 +1267,74 @@ Data ñaca. - --5ysVjo5_4q8-- + --xI6KtaQs2a4-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1274812262" -_ queue.go:308 Queue.SendLoop fmMxe0VzZss: from from -_ queue.go:367 Queue.SendLoop fmMxe0VzZss: nodomain sent -_ queue.go:367 Queue.SendLoop fmMxe0VzZss: am@loco sent -_ queue.go:367 Queue.SendLoop fmMxe0VzZss: x@remote sent -_ queue.go:342 Queue.SendLoop fmMxe0VzZss: all done ---- PASS: TestBasic (0.10s) + testlib.go:32: test directory: "/tmp/testlib_1169394835" +_ queue.go:308 Queue.SendLoop i53XQltmwoQ: from from +_ queue.go:367 Queue.SendLoop i53XQltmwoQ: nodomain sent +_ queue.go:367 Queue.SendLoop i53XQltmwoQ: am@loco sent +_ queue.go:367 Queue.SendLoop i53XQltmwoQ: x@remote sent +_ queue.go:342 Queue.SendLoop i53XQltmwoQ: all done +--- PASS: TestBasic (0.43s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_1049022624" -_ queue.go:308 Queue.SendLoop iHbOA3VNQXw: from from@loco -_ queue.go:465 Queue.SendLoop iHbOA3VNQXw: queued DSN: yMmQBOY4wJA -_ queue.go:342 Queue.SendLoop iHbOA3VNQXw: all done -_ queue.go:308 Queue.SendLoop yMmQBOY4wJA: from <> -_ queue.go:367 Queue.SendLoop yMmQBOY4wJA: from@loco sent -_ queue.go:342 Queue.SendLoop yMmQBOY4wJA: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_1049022624/m:yMmQBOY4wJA": remove /tmp/testlib_1049022624/m:yMmQBOY4wJA: no such file or directory ---- PASS: TestDSNOnTimeout (0.11s) + testlib.go:32: test directory: "/tmp/testlib_1696042893" +_ queue.go:308 Queue.SendLoop v2csCXnlzrU: from from@loco +_ queue.go:465 Queue.SendLoop v2csCXnlzrU: queued DSN: XUH3XHYOFF0 +_ queue.go:342 Queue.SendLoop v2csCXnlzrU: all done +_ queue.go:308 Queue.SendLoop XUH3XHYOFF0: from <> +_ queue.go:367 Queue.SendLoop XUH3XHYOFF0: from@loco sent +_ queue.go:342 Queue.SendLoop XUH3XHYOFF0: all done +--- PASS: TestDSNOnTimeout (0.14s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_548900798" -_ queue.go:308 Queue.SendLoop S-06O54Ecqk: from from -_ queue.go:367 Queue.SendLoop S-06O54Ecqk: ata@hualpa sent -_ queue.go:367 Queue.SendLoop S-06O54Ecqk: pq@loco sent -_ queue.go:367 Queue.SendLoop S-06O54Ecqk: rs@loco sent -_ queue.go:342 Queue.SendLoop S-06O54Ecqk: all done ---- PASS: TestAliases (0.10s) + testlib.go:32: test directory: "/tmp/testlib_4082274171" +_ queue.go:308 Queue.SendLoop KHAuTpNG0Qw: from from +_ queue.go:367 Queue.SendLoop KHAuTpNG0Qw: ata@hualpa sent +_ queue.go:367 Queue.SendLoop KHAuTpNG0Qw: pq@loco sent +_ queue.go:367 Queue.SendLoop KHAuTpNG0Qw: rs@loco sent +_ queue.go:342 Queue.SendLoop KHAuTpNG0Qw: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_4082274171/m:KHAuTpNG0Qw": remove /tmp/testlib_4082274171/m:KHAuTpNG0Qw: no such file or directory +--- PASS: TestAliases (0.31s) === RUN TestFullQueue - testlib.go:32: test directory: "/tmp/testlib_2840119461" + testlib.go:32: test directory: "/tmp/testlib_2327236988" _ queue.go:164 Queue.Put from: error: queue full -_ queue.go:308 Queue.SendLoop Zho_uIV3P0c: from from -_ queue.go:367 Queue.SendLoop Zho_uIV3P0c: to sent -_ queue.go:342 Queue.SendLoop Zho_uIV3P0c: all done ---- PASS: TestFullQueue (0.03s) +--- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_1328496213" +_ queue.go:308 Queue.SendLoop YiGi1TxTpDo: from from + testlib.go:32: test directory: "/tmp/testlib_3232525735" +_ queue.go:367 Queue.SendLoop YiGi1TxTpDo: to sent +_ queue.go:375 Queue.SendLoop YiGi1TxTpDo: error: failed to write: open /tmp/testlib_2327236988/.m:YiGi1TxTpDo774140595: no such file or directory +_ queue.go:342 Queue.SendLoop YiGi1TxTpDo: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_2327236988/m:YiGi1TxTpDo": remove /tmp/testlib_2327236988/m:YiGi1TxTpDo: 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_2178657595" -_ queue.go:308 Queue.SendLoop BfVnsYkqNsU: from from@loco -_ queue.go:367 Queue.SendLoop BfVnsYkqNsU: to@to sent -_ queue.go:342 Queue.SendLoop BfVnsYkqNsU: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_2178657595/m:BfVnsYkqNsU": remove /tmp/testlib_2178657595/m:BfVnsYkqNsU: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_2337802584" +_ queue.go:308 Queue.SendLoop MMMi1-0miZc: from from@loco +_ queue.go:367 Queue.SendLoop MMMi1-0miZc: to@to sent +_ queue.go:342 Queue.SendLoop MMMi1-0miZc: all done --- PASS: TestSerialization (0.11s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.499s +ok blitiri.com.ar/go/chasquid/internal/queue 1.001s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_1270018613" ---- PASS: TestWriteFile (0.03s) + testlib.go:32: test directory: "/tmp/testlib_1777639654" +--- PASS: TestWriteFile (0.27s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_2880129954" + testlib.go:32: test directory: "/tmp/testlib_2986655141" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_3014900959" + testlib.go:32: test directory: "/tmp/testlib_1158661553" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.030s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.272s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.002s +ok blitiri.com.ar/go/chasquid/internal/set 0.004s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1313,16 +1350,16 @@ === RUN TestLineTooLong --- PASS: TestLineTooLong (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.019s +ok blitiri.com.ar/go/chasquid/internal/smtp 0.017s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] -_ server.go:242 Server listening on 127.0.0.1:36327 (SMTP) -2024-05-09 10:24:01.102604 daemon listening on 127.0.0.1:36327 -_ server.go:242 Server listening on 127.0.0.1:34409 (submission) -2024-05-09 10:24:01.102706 daemon listening on 127.0.0.1:34409 -_ server.go:242 Server listening on 127.0.0.1:39053 (submission+TLS) -2024-05-09 10:24:01.102736 daemon listening on 127.0.0.1:39053 +_ server.go:242 Server listening on 127.0.0.1:38155 (SMTP) +2025-06-11 16:49:33.450805 daemon listening on 127.0.0.1:38155 +_ server.go:242 Server listening on 127.0.0.1:45253 (submission) +2025-06-11 16:49:33.450912 daemon listening on 127.0.0.1:45253 +_ server.go:242 Server listening on 127.0.0.1:40195 (submission+TLS) +2025-06-11 16:49:33.450944 daemon listening on 127.0.0.1:40195 === RUN TestSecLevel -_ conn.go:188 SMTP.Conn 127.0.0.1:53578: error: error completing TLS handshake: EOF +_ conn.go:188 SMTP.Conn 127.0.0.1:35764: 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) @@ -1338,224 +1375,224 @@ === RUN TestReadUntilDotReadError --- PASS: TestReadUntilDotReadError (0.00s) === RUN TestSimple -_ conn.go:689 SMTP.Conn 127.0.0.1:48010: Queued from from@from to [to@localhost] - XM0wFjTRBvA -2024-05-09 10:24:01.305401 XM0wFjTRBvA from=from@from queued ip=127.0.0.1:48010 to=[to@localhost] -_ queue.go:308 Queue.SendLoop XM0wFjTRBvA: from from@from -_ queue.go:367 Queue.SendLoop XM0wFjTRBvA: testuser@localhost sent -2024-05-09 10:24:01.305625 XM0wFjTRBvA from=from@from to=testuser@localhost sent +_ conn.go:689 SMTP.Conn 127.0.0.1:36240: Queued from from@from to [to@localhost] - PU7VnmS_GdE +2025-06-11 16:49:33.657696 PU7VnmS_GdE from=from@from queued ip=127.0.0.1:36240 to=[to@localhost] +_ queue.go:308 Queue.SendLoop PU7VnmS_GdE: from from@from +_ queue.go:367 Queue.SendLoop PU7VnmS_GdE: testuser@localhost sent +2025-06-11 16:49:33.657899 PU7VnmS_GdE from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop PU7VnmS_GdE: all done +2025-06-11 16:49:33.658256 PU7VnmS_GdE from=from@from all done --- PASS: TestSimple (0.00s) === RUN TestSimpleTLS -_ queue.go:342 Queue.SendLoop XM0wFjTRBvA: all done -2024-05-09 10:24:01.416879 XM0wFjTRBvA from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48016: Queued from from@from to [to@localhost] - ok9F2cXxAd8 -2024-05-09 10:24:01.417051 ok9F2cXxAd8 from=from@from queued ip=127.0.0.1:48016 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ok9F2cXxAd8: from from@from -_ queue.go:367 Queue.SendLoop ok9F2cXxAd8: testuser@localhost sent -2024-05-09 10:24:01.417224 ok9F2cXxAd8 from=from@from to=testuser@localhost sent ---- PASS: TestSimpleTLS (0.11s) +_ conn.go:689 SMTP.Conn 127.0.0.1:36250: Queued from from@from to [to@localhost] - R5n2x6xu9lY +2025-06-11 16:49:33.663296 R5n2x6xu9lY from=from@from queued ip=127.0.0.1:36250 to=[to@localhost] +_ queue.go:308 Queue.SendLoop R5n2x6xu9lY: from from@from +_ queue.go:367 Queue.SendLoop R5n2x6xu9lY: testuser@localhost sent +2025-06-11 16:49:33.663629 R5n2x6xu9lY from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop R5n2x6xu9lY: all done +2025-06-11 16:49:33.664148 R5n2x6xu9lY from=from@from all done +--- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ queue.go:342 Queue.SendLoop ok9F2cXxAd8: all done -2024-05-09 10:24:01.417486 ok9F2cXxAd8 from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48032: Queued from from@from to [to@localhost] - UnfaadFcQ2Y -2024-05-09 10:24:01.479076 UnfaadFcQ2Y from=from@from queued ip=127.0.0.1:48032 to=[to@localhost] -_ queue.go:308 Queue.SendLoop UnfaadFcQ2Y: from from@from -_ queue.go:367 Queue.SendLoop UnfaadFcQ2Y: testuser@localhost sent -2024-05-09 10:24:01.479483 UnfaadFcQ2Y from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop UnfaadFcQ2Y: all done -2024-05-09 10:24:01.480011 UnfaadFcQ2Y from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48032: Queued from from@from to [to@localhost] - 4msIpTXoTps -2024-05-09 10:24:01.480503 4msIpTXoTps from=from@from queued ip=127.0.0.1:48032 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 4msIpTXoTps: from from@from -_ queue.go:367 Queue.SendLoop 4msIpTXoTps: testuser@localhost sent -2024-05-09 10:24:01.480606 4msIpTXoTps from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 4msIpTXoTps: all done -2024-05-09 10:24:01.480849 4msIpTXoTps from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48032: Queued from from@from to [to@localhost] - sJpxUCyc2vY -2024-05-09 10:24:01.481684 sJpxUCyc2vY from=from@from queued ip=127.0.0.1:48032 to=[to@localhost] -_ queue.go:308 Queue.SendLoop sJpxUCyc2vY: from from@from -_ queue.go:367 Queue.SendLoop sJpxUCyc2vY: testuser@localhost sent -2024-05-09 10:24:01.481808 sJpxUCyc2vY from=from@from to=testuser@localhost sent ---- PASS: TestManyEmails (0.06s) +_ conn.go:689 SMTP.Conn 127.0.0.1:36254: Queued from from@from to [to@localhost] - w4sbkqDD3O0 +2025-06-11 16:49:33.668381 w4sbkqDD3O0 from=from@from queued ip=127.0.0.1:36254 to=[to@localhost] +_ queue.go:308 Queue.SendLoop w4sbkqDD3O0: from from@from +_ queue.go:367 Queue.SendLoop w4sbkqDD3O0: testuser@localhost sent +2025-06-11 16:49:33.668694 w4sbkqDD3O0 from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop w4sbkqDD3O0: all done +2025-06-11 16:49:33.669060 w4sbkqDD3O0 from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:36254: Queued from from@from to [to@localhost] - -sIdhBCAhBY +2025-06-11 16:49:33.669695 -sIdhBCAhBY from=from@from queued ip=127.0.0.1:36254 to=[to@localhost] +_ queue.go:308 Queue.SendLoop -sIdhBCAhBY: from from@from +_ queue.go:367 Queue.SendLoop -sIdhBCAhBY: testuser@localhost sent +2025-06-11 16:49:33.669828 -sIdhBCAhBY from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop -sIdhBCAhBY: all done +2025-06-11 16:49:33.670219 -sIdhBCAhBY from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:36254: Queued from from@from to [to@localhost] - QewyC6gh7lA +2025-06-11 16:49:33.671683 QewyC6gh7lA from=from@from queued ip=127.0.0.1:36254 to=[to@localhost] +_ queue.go:308 Queue.SendLoop QewyC6gh7lA: from from@from +_ queue.go:367 Queue.SendLoop QewyC6gh7lA: testuser@localhost sent +2025-06-11 16:49:33.671944 QewyC6gh7lA from=from@from to=testuser@localhost sent +--- PASS: TestManyEmails (0.01s) +_ queue.go:342 Queue.SendLoop QewyC6gh7lA: all done +2025-06-11 16:49:33.672213 QewyC6gh7lA from=from@from all done === RUN TestAuth -_ queue.go:342 Queue.SendLoop sJpxUCyc2vY: all done -2024-05-09 10:24:01.575331 sJpxUCyc2vY from=from@from all done -2024-05-09 10:24:01.588009 127.0.0.1:32940 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:32940: Queued from testuser@localhost to [to@localhost] - e7UIoonvGas -2024-05-09 10:24:01.588899 e7UIoonvGas from=testuser@localhost queued ip=127.0.0.1:32940 to=[to@localhost] -_ queue.go:308 Queue.SendLoop e7UIoonvGas: from testuser@localhost -_ queue.go:367 Queue.SendLoop e7UIoonvGas: testuser@localhost sent -2024-05-09 10:24:01.589007 e7UIoonvGas from=testuser@localhost to=testuser@localhost sent +2025-06-11 16:49:33.779953 127.0.0.1:50514 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:50514: Queued from testuser@localhost to [to@localhost] - Mit27BWCcJI +2025-06-11 16:49:33.780872 Mit27BWCcJI from=testuser@localhost queued ip=127.0.0.1:50514 to=[to@localhost] +_ queue.go:308 Queue.SendLoop Mit27BWCcJI: from testuser@localhost +_ queue.go:367 Queue.SendLoop Mit27BWCcJI: testuser@localhost sent +2025-06-11 16:49:33.781055 Mit27BWCcJI from=testuser@localhost to=testuser@localhost sent --- PASS: TestAuth (0.11s) === RUN TestSubmissionWithoutAuth -_ conn.go:297 SMTP.Conn 127.0.0.1:32950: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ queue.go:342 Queue.SendLoop Mit27BWCcJI: all done +2025-06-11 16:49:33.781486 Mit27BWCcJI from=testuser@localhost all done +_ conn.go:297 SMTP.Conn 127.0.0.1:50522: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.00s) === RUN TestAuthOnTLS -_ queue.go:342 Queue.SendLoop e7UIoonvGas: all done -2024-05-09 10:24:01.677352 e7UIoonvGas from=testuser@localhost all done -2024-05-09 10:24:01.694704 127.0.0.1:53584 auth succeeded for testuser@localhost -_ queue.go:308 Queue.SendLoop 5c7wp3nlyDc: from testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:53584: Queued from testuser@localhost to [to@localhost] - 5c7wp3nlyDc -_ queue.go:367 Queue.SendLoop 5c7wp3nlyDc: testuser@localhost sent -2024-05-09 10:24:01.718898 5c7wp3nlyDc from=testuser@localhost queued ip=127.0.0.1:53584 to=[to@localhost] -2024-05-09 10:24:01.718916 5c7wp3nlyDc from=testuser@localhost to=testuser@localhost sent ---- PASS: TestAuthOnTLS (0.13s) -_ queue.go:342 Queue.SendLoop 5c7wp3nlyDc: all done -2024-05-09 10:24:01.719272 5c7wp3nlyDc from=testuser@localhost all done +2025-06-11 16:49:33.895280 127.0.0.1:35766 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:35766: Queued from testuser@localhost to [to@localhost] - kGuKKeSCzPw +2025-06-11 16:49:33.906871 kGuKKeSCzPw from=testuser@localhost queued ip=127.0.0.1:35766 to=[to@localhost] +_ queue.go:308 Queue.SendLoop kGuKKeSCzPw: from testuser@localhost +_ queue.go:367 Queue.SendLoop kGuKKeSCzPw: testuser@localhost sent +2025-06-11 16:49:33.907034 kGuKKeSCzPw from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop kGuKKeSCzPw: all done +2025-06-11 16:49:33.907292 kGuKKeSCzPw from=testuser@localhost all done +--- PASS: TestAuthOnTLS (0.12s) === RUN TestAuthOnSMTP -2024-05-09 10:24:01.827163 127.0.0.1:48042 auth succeeded for testuser@localhost -_ conn.go:689 SMTP.Conn 127.0.0.1:48042: Queued from testuser@localhost to [to@localhost] - c4kcpcbLOkc -2024-05-09 10:24:01.828325 c4kcpcbLOkc from=testuser@localhost queued ip=127.0.0.1:48042 to=[to@localhost] -_ queue.go:308 Queue.SendLoop c4kcpcbLOkc: from testuser@localhost -_ queue.go:367 Queue.SendLoop c4kcpcbLOkc: testuser@localhost sent -2024-05-09 10:24:01.828447 c4kcpcbLOkc from=testuser@localhost to=testuser@localhost sent +2025-06-11 16:49:34.017137 127.0.0.1:36270 auth succeeded for testuser@localhost +_ conn.go:689 SMTP.Conn 127.0.0.1:36270: Queued from testuser@localhost to [to@localhost] - 5_lEAVflgp0 +2025-06-11 16:49:34.018146 5_lEAVflgp0 from=testuser@localhost queued ip=127.0.0.1:36270 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 5_lEAVflgp0: from testuser@localhost +_ queue.go:367 Queue.SendLoop 5_lEAVflgp0: testuser@localhost sent +2025-06-11 16:49:34.018280 5_lEAVflgp0 from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 5_lEAVflgp0: all done +2025-06-11 16:49:34.018670 5_lEAVflgp0 from=testuser@localhost all done --- PASS: TestAuthOnSMTP (0.11s) === RUN TestBrokenAuth -_ queue.go:342 Queue.SendLoop c4kcpcbLOkc: all done -2024-05-09 10:24:01.922871 c4kcpcbLOkc from=testuser@localhost all done -_ conn.go:1062 SMTP.Conn 127.0.0.1:32960: error: error authenticating \"user\"@\"broken\": failed to auth -2024-05-09 10:24:01.932313 127.0.0.1:32960 auth failed for user@broken -_ conn.go:297 SMTP.Conn 127.0.0.1:32960: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:297 SMTP.Conn 127.0.0.1:32960: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command ---- PASS: TestBrokenAuth (0.10s) +_ conn.go:1062 SMTP.Conn 127.0.0.1:50528: error: error authenticating \"user\"@\"broken\": failed to auth +2025-06-11 16:49:34.125377 127.0.0.1:50528 auth failed for user@broken +_ conn.go:297 SMTP.Conn 127.0.0.1:50528: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:297 SMTP.Conn 127.0.0.1:50528: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +--- PASS: TestBrokenAuth (0.11s) === RUN TestWrongMailParsing -_ conn.go:297 SMTP.Conn 127.0.0.1:48054: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48054: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48054: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48054: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48066: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48066: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48066: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48066: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48070: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48070: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48070: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48070: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48074: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48074: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48074: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48074: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48088: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48088: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48088: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48088: error: too many errors, breaking connection -_ conn.go:297 SMTP.Conn 127.0.0.1:48098: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:297 SMTP.Conn 127.0.0.1:48098: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:297 SMTP.Conn 127.0.0.1:48098: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48098: error: too many errors, breaking connection ---- PASS: TestWrongMailParsing (0.01s) +_ conn.go:297 SMTP.Conn 127.0.0.1:36282: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36282: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36282: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36282: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36288: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36288: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36288: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36288: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36294: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36294: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36294: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36294: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36296: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36296: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36296: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36296: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36300: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36300: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36300: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36300: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36312: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:297 SMTP.Conn 127.0.0.1:36312: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:297 SMTP.Conn 127.0.0.1:36312: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36312: 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:48122: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:297 SMTP.Conn 127.0.0.1:36330: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.00s) === RUN TestRelayForbidden -2024-05-09 10:24:01.941815 127.0.0.1:48128 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48128: error: RCPT failed: 503 5.7.1 Relay not allowed +2025-06-11 16:49:34.135502 127.0.0.1:36342 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36342: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2024-05-09 10:24:02.052645 127.0.0.1:32962 auth succeeded for testuser@localhost -_ conn.go:297 SMTP.Conn 127.0.0.1:32962: error: RCPT failed: 452 4.5.3 Too many recipients +2025-06-11 16:49:34.239059 127.0.0.1:50536 auth succeeded for testuser@localhost +_ conn.go:297 SMTP.Conn 127.0.0.1:50536: 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:48140: error: error checking if user \"to@broken\" exists: failed to check if user exists -2024-05-09 10:24:02.057976 127.0.0.1:48140 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:48140: error: RCPT failed: 451 4.4.3 Temporary error checking address +_ conn.go:601 SMTP.Conn 127.0.0.1:36344: error: error checking if user \"to@broken\" exists: failed to check if user exists +2025-06-11 16:49:34.247473 127.0.0.1:36344 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:36344: error: RCPT failed: 451 4.4.3 Temporary error checking address --- PASS: TestRcptBrokenExists (0.00s) === RUN TestRcptUserDoesNotExist -2024-05-09 10:24:02.060285 127.0.0.1:48146 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:297 SMTP.Conn 127.0.0.1:48146: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +2025-06-11 16:49:34.249906 127.0.0.1:36346 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:297 SMTP.Conn 127.0.0.1:36346: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptUserDoesNotExist (0.00s) === RUN TestTooMuchData -_ conn.go:689 SMTP.Conn 127.0.0.1:48160: Queued from from@from to [to@localhost] - uyZV70SPoe4 -2024-05-09 10:24:02.137526 uyZV70SPoe4 from=from@from queued ip=127.0.0.1:48160 to=[to@localhost] -_ queue.go:308 Queue.SendLoop uyZV70SPoe4: from from@from -_ queue.go:367 Queue.SendLoop uyZV70SPoe4: testuser@localhost sent -2024-05-09 10:24:02.137685 uyZV70SPoe4 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop uyZV70SPoe4: all done -2024-05-09 10:24:02.153216 uyZV70SPoe4 from=from@from all done -_ conn.go:689 SMTP.Conn 127.0.0.1:48160: Queued from from@from to [to@localhost] - djaxzj-VSf0 -2024-05-09 10:24:02.221856 djaxzj-VSf0 from=from@from queued ip=127.0.0.1:48160 to=[to@localhost] -_ queue.go:308 Queue.SendLoop djaxzj-VSf0: from from@from -_ queue.go:367 Queue.SendLoop djaxzj-VSf0: testuser@localhost sent -2024-05-09 10:24:02.222010 djaxzj-VSf0 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop djaxzj-VSf0: all done -2024-05-09 10:24:02.256443 djaxzj-VSf0 from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:48160: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:689 SMTP.Conn 127.0.0.1:48160: Queued from from@from to [to@localhost] - 0RV7inlABH8 -2024-05-09 10:24:02.378556 0RV7inlABH8 from=from@from queued ip=127.0.0.1:48160 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 0RV7inlABH8: from from@from -_ queue.go:367 Queue.SendLoop 0RV7inlABH8: testuser@localhost sent -2024-05-09 10:24:02.378805 0RV7inlABH8 from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.32s) +_ conn.go:689 SMTP.Conn 127.0.0.1:36360: Queued from from@from to [to@localhost] - UxwC6Pprhyg +2025-06-11 16:49:34.347038 UxwC6Pprhyg from=from@from queued ip=127.0.0.1:36360 to=[to@localhost] +_ queue.go:308 Queue.SendLoop UxwC6Pprhyg: from from@from +_ queue.go:367 Queue.SendLoop UxwC6Pprhyg: testuser@localhost sent +2025-06-11 16:49:34.347247 UxwC6Pprhyg from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop UxwC6Pprhyg: all done +2025-06-11 16:49:34.438769 UxwC6Pprhyg from=from@from all done +_ conn.go:689 SMTP.Conn 127.0.0.1:36360: Queued from from@from to [to@localhost] - guXKGU98o9c +2025-06-11 16:49:34.446536 guXKGU98o9c from=from@from queued ip=127.0.0.1:36360 to=[to@localhost] +_ queue.go:308 Queue.SendLoop guXKGU98o9c: from from@from +_ queue.go:367 Queue.SendLoop guXKGU98o9c: testuser@localhost sent +2025-06-11 16:49:34.446899 guXKGU98o9c from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop guXKGU98o9c: all done +2025-06-11 16:49:34.466689 guXKGU98o9c from=from@from all done +_ conn.go:297 SMTP.Conn 127.0.0.1:36360: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:689 SMTP.Conn 127.0.0.1:36360: Queued from from@from to [to@localhost] - Kc4iX_KkUFY +2025-06-11 16:49:34.619483 Kc4iX_KkUFY from=from@from queued ip=127.0.0.1:36360 to=[to@localhost] +_ queue.go:308 Queue.SendLoop Kc4iX_KkUFY: from from@from +_ queue.go:367 Queue.SendLoop Kc4iX_KkUFY: testuser@localhost sent +2025-06-11 16:49:34.619733 Kc4iX_KkUFY from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (0.37s) === RUN TestSimpleCommands -_ conn.go:297 SMTP.Conn 127.0.0.1:48170: 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:48170: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +_ conn.go:297 SMTP.Conn 127.0.0.1:36362: 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:36362: 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:48186: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:327 SMTP.Conn 127.0.0.1:48186: error: exiting with error: line too long +_ conn.go:297 SMTP.Conn 127.0.0.1:36372: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:327 SMTP.Conn 127.0.0.1:36372: 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:48208: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:297 SMTP.Conn 127.0.0.1:36392: 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:53594: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:297 SMTP.Conn 127.0.0.1:35774: 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:48220: error: RCPT failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:48220: error: RCPT failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:48220: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:304 SMTP.Conn 127.0.0.1:48220: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36408: error: RCPT failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:36408: error: RCPT failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:36408: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:304 SMTP.Conn 127.0.0.1:36408: error: too many errors, breaking connection === RUN FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 -_ conn.go:297 SMTP.Conn 127.0.0.1:48224: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:48224: error: DATA failed: 503 5.5.1 Sender not yet given -2024-05-09 10:24:02.387876 127.0.0.1:48224 rejected from=a@b to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48224: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:304 SMTP.Conn 127.0.0.1:48224: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36416: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:36416: error: DATA failed: 503 5.5.1 Sender not yet given +2025-06-11 16:49:34.628313 127.0.0.1:36416 rejected from=a@b to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36416: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:304 SMTP.Conn 127.0.0.1:36416: error: too many errors, breaking connection === RUN FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f -2024-05-09 10:24:02.388525 127.0.0.1:48240 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48240: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48240: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:48240: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:48240: error: too many errors, breaking connection +2025-06-11 16:49:34.628963 127.0.0.1:36426 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36426: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36426: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:36426: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:36426: error: too many errors, breaking connection === RUN FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe -_ conn.go:297 SMTP.Conn 127.0.0.1:48246: error: MAIL failed: 500 5.5.2 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:48246: error: MAIL failed: 500 5.5.4 Malformed command: EOF -_ conn.go:297 SMTP.Conn 127.0.0.1:48246: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:304 SMTP.Conn 127.0.0.1:48246: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36430: error: MAIL failed: 500 5.5.2 Unknown command +_ conn.go:297 SMTP.Conn 127.0.0.1:36430: error: MAIL failed: 500 5.5.4 Malformed command: EOF +_ conn.go:297 SMTP.Conn 127.0.0.1:36430: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:304 SMTP.Conn 127.0.0.1:36430: error: too many errors, breaking connection === RUN FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 -_ conn.go:297 SMTP.Conn 127.0.0.1:48260: error: HELO failed: 501 Invisible customers are not welcome! -_ conn.go:297 SMTP.Conn 127.0.0.1:48260: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:36446: error: HELO failed: 501 Invisible customers are not welcome! +_ conn.go:297 SMTP.Conn 127.0.0.1:36446: error: EHLO failed: 501 Invisible customers are not welcome! === RUN FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf === RUN FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 -2024-05-09 10:24:02.391220 127.0.0.1:48272 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48272: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:297 SMTP.Conn 127.0.0.1:48272: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:297 SMTP.Conn 127.0.0.1:48272: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:304 SMTP.Conn 127.0.0.1:48272: error: too many errors, breaking connection +2025-06-11 16:49:34.632542 127.0.0.1:36460 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36460: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:297 SMTP.Conn 127.0.0.1:36460: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:297 SMTP.Conn 127.0.0.1:36460: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:304 SMTP.Conn 127.0.0.1:36460: error: too many errors, breaking connection === RUN FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 -_ queue.go:342 Queue.SendLoop 0RV7inlABH8: all done -2024-05-09 10:24:02.391626 0RV7inlABH8 from=from@from all done -_ conn.go:297 SMTP.Conn 127.0.0.1:48280: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:48280: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:297 SMTP.Conn 127.0.0.1:48280: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:304 SMTP.Conn 127.0.0.1:48280: error: too many errors, breaking connection +_ conn.go:297 SMTP.Conn 127.0.0.1:36470: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:36470: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:36470: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:304 SMTP.Conn 127.0.0.1:36470: error: too many errors, breaking connection === RUN FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded +_ conn.go:297 SMTP.Conn 127.0.0.1:36484: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command === RUN FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 -_ conn.go:297 SMTP.Conn 127.0.0.1:48288: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command -_ conn.go:297 SMTP.Conn 127.0.0.1:48298: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:297 SMTP.Conn 127.0.0.1:36490: error: AUTH failed: 503 5.7.10 You feel vulnerable === RUN FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c -_ conn.go:297 SMTP.Conn 127.0.0.1:53606: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage -_ conn.go:297 SMTP.Conn 127.0.0.1:53606: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 -2024-05-09 10:24:02.497907 127.0.0.1:53606 auth failed for user@testserver -_ conn.go:297 SMTP.Conn 127.0.0.1:53606: error: AUTH failed: 535 5.7.8 Incorrect user or password -_ conn.go:304 SMTP.Conn 127.0.0.1:53606: error: too many errors, breaking connection ---- PASS: FuzzConnection (0.11s) +_ conn.go:297 SMTP.Conn 127.0.0.1:35778: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage +_ conn.go:297 SMTP.Conn 127.0.0.1:35778: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 +_ queue.go:342 Queue.SendLoop Kc4iX_KkUFY: all done +2025-06-11 16:49:34.640112 Kc4iX_KkUFY from=from@from all done +2025-06-11 16:49:34.750703 127.0.0.1:35778 auth failed for user@testserver +_ conn.go:297 SMTP.Conn 127.0.0.1:35778: error: AUTH failed: 535 5.7.8 Incorrect user or password +_ conn.go:304 SMTP.Conn 127.0.0.1:35778: error: too many errors, breaking connection +--- PASS: FuzzConnection (0.13s) --- PASS: FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 (0.00s) --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.00s) --- PASS: FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f (0.00s) @@ -1566,9 +1603,9 @@ --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.00s) --- PASS: FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded (0.00s) --- PASS: FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 (0.00s) - --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.11s) + --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.12s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.485s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 1.406s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -1588,29 +1625,29 @@ sts_test.go:242: got error as expected: MTA-STS TXT record missing --- PASS: TestPolicyTooBig (0.01s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_2756716953" + testlib.go:32: test directory: "/tmp/testlib_2532036531" 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.06s) +--- PASS: TestCacheBasics (0.09s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_441549406" + testlib.go:32: test directory: "/tmp/testlib_3159485768" sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} ---- PASS: TestCacheBadData (0.21s) +--- PASS: TestCacheBadData (0.17s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_1724935808" + testlib.go:32: test directory: "/tmp/testlib_2664941796" 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.09s) +--- PASS: TestCacheRefresh (0.08s) === RUN TestCacheSlashSafe - testlib.go:32: test directory: "/tmp/testlib_4216278288" + testlib.go:32: test directory: "/tmp/testlib_2308230768" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1618,11 +1655,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.395s +ok blitiri.com.ar/go/chasquid/internal/sts 0.409s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_3867735534" + testlib.go:32: test directory: "/tmp/testlib_1485081477" --- 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_1328029025" + testlib.go:32: test directory: "/tmp/testlib_3528425310" --- 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_3098064980" + testlib.go:32: test directory: "/tmp/testlib_1250732998" --- PASS: TestGenerateCert (0.02s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.01s) +--- PASS: TestGenerateCertBadDir (0.03s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.059s +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.003s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.042s === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test73560611" - userdb_test.go:37: file: "/tmp/userdb_test198772099" + userdb_test.go:37: file: "/tmp/userdb_test2741328350" + userdb_test.go:37: file: "/tmp/userdb_test3624682757" --- PASS: TestEmptyLoad (0.00s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test4135297381" ---- PASS: TestWrite (0.47s) + userdb_test.go:37: file: "/tmp/userdb_test1113843807" +--- PASS: TestWrite (0.42s) === RUN TestNew ---- PASS: TestNew (0.06s) +--- PASS: TestNew (0.05s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test858414663" + userdb_test.go:37: file: "/tmp/userdb_test744119728" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test4223836814" ---- PASS: TestPlainScheme (0.00s) + userdb_test.go:37: file: "/tmp/userdb_test1515078136" +--- PASS: TestPlainScheme (0.04s) === RUN TestDeniedScheme - userdb_test.go:37: file: "/tmp/userdb_test2907017155" ---- PASS: TestDeniedScheme (0.00s) + userdb_test.go:37: file: "/tmp/userdb_test3949288477" +--- PASS: TestDeniedScheme (0.07s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test2918019732" ---- PASS: TestReload (0.00s) + userdb_test.go:37: file: "/tmp/userdb_test200765263" +--- PASS: TestReload (0.05s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test3412162948" + userdb_test.go:37: file: "/tmp/userdb_test926233293" --- PASS: TestRemoveUser (0.06s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test4183798862" ---- PASS: TestExists (0.06s) + userdb_test.go:37: file: "/tmp/userdb_test1904284743" +--- PASS: TestExists (0.07s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 0.651s +ok blitiri.com.ar/go/chasquid/internal/userdb 0.807s 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' in '../chasquid_1.13-1_amd64.deb'. dpkg-deb: building package 'chasquid-dbgsym' in '../chasquid-dbgsym_1.13-1_amd64.deb'. +dpkg-deb: building package 'chasquid' in '../chasquid_1.13-1_amd64.deb'. dpkg-genbuildinfo --build=binary -O../chasquid_1.13-1_amd64.buildinfo dpkg-genchanges --build=binary -O../chasquid_1.13-1_amd64.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/3063882/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/3063882/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/2269354 and its subdirectories -I: Current time: Wed May 8 22:24:21 -12 2024 -I: pbuilder-time-stamp: 1715250261 +I: removing directory /srv/workspace/pbuilder/3063882 and its subdirectories +I: Current time: Thu Jun 12 06:49:56 +14 2025 +I: pbuilder-time-stamp: 1749660596