Diff of the two buildlogs: -- --- b1/build.log 2023-04-12 13:52:23.606439543 +0000 +++ b2/build.log 2023-04-12 13:56:29.693566377 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue May 14 08:12:54 -12 2024 -I: pbuilder-time-stamp: 1715717574 +I: Current time: Thu Apr 13 03:52:31 +14 2023 +I: pbuilder-time-stamp: 1681307551 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./chasquid_1.11.orig.tar.xz] I: copying [./chasquid_1.11-2.debian.tar.xz] I: Extracting source -gpgv: Signature made Mon Feb 27 01:23:55 2023 -12 +gpgv: Signature made Tue Feb 28 03:23:55 2023 +14 gpgv: using EDDSA key A095B66EE09024BEE6A2F0722A27904BD7243EDA gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./chasquid_1.11-2.dsc: no acceptable signature found @@ -27,11 +27,20 @@ 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/24386/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/D01_modify_environment starting +debug: Running on codethink16-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 +lrwxrwxrwx 1 root root 4 Jan 6 03:20 /bin/sh -> dash +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/30232/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/D02_print_environment starting I: set BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' + 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=8' @@ -41,38 +50,38 @@ IFS=' ' LANG='C' - LANGUAGE='en_US:en' + LANGUAGE='nl_BE:nl' LC_ALL='C' MAIL='/var/mail/root' OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' + 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' - PPID='24386' + PPID='30232' PS1='# ' PS2='> ' 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.IuzT0PF9/pbuilderrc_hX5p --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.IuzT0PF9/b1 --logfile b1/build.log chasquid_1.11-2.dsc' + 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.IuzT0PF9/pbuilderrc_rpwH --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.IuzT0PF9/b2 --logfile b2/build.log --extrapackages usrmerge chasquid_1.11-2.dsc' SUDO_GID='117' SUDO_UID='110' SUDO_USER='jenkins' TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' + TZ='/usr/share/zoneinfo/Etc/GMT-14' USER='root' USERNAME='root' _='/usr/bin/systemd-run' http_proxy='http://192.168.101.16:3128' I: uname -a - Linux codethink11-arm64 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-208-generic #220-Ubuntu SMP Mon Mar 20 14:28:12 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 12 04:50 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/24386/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 12 00:26 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -176,7 +185,7 @@ Get: 44 http://deb.debian.org/debian bookworm/main arm64 golang-blitiri-go-log-dev all 1.1.0-1 [6724 B] Get: 45 http://deb.debian.org/debian bookworm/main arm64 golang-blitiri-go-spf-dev all 1.5.1-1 [37.2 kB] Get: 46 http://deb.debian.org/debian bookworm/main arm64 golang-blitiri-go-systemd-dev all 1.1.0-1 [6952 B] -Fetched 100 MB in 2s (55.4 MB/s) +Fetched 100 MB in 2s (53.7 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19616 files and directories currently installed.) @@ -374,8 +383,17 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/chasquid-1.11/ && 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.11-2_source.changes +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/chasquid-1.11/ && 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.11-2_source.changes dpkg-buildpackage: info: source package chasquid dpkg-buildpackage: info: source version 1.11-2 dpkg-buildpackage: info: source distribution unstable @@ -397,113 +415,118 @@ dh_auto_build -- -ldflags " -X main.version=1.11-2 -X main.sourceDateTs=1677503939" cd obj-aarch64-linux-gnu && go install -trimpath -v -p 8 -ldflags " -X main.version=1.11-2 -X main.sourceDateTs=1677503939" 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/maillog blitiri.com.ar/go/chasquid/internal/nettrace blitiri.com.ar/go/chasquid/internal/normalize blitiri.com.ar/go/chasquid/internal/protoio blitiri.com.ar/go/chasquid/internal/protoio/testpb blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/safeio blitiri.com.ar/go/chasquid/internal/set blitiri.com.ar/go/chasquid/internal/smtp blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid/internal/sts blitiri.com.ar/go/chasquid/internal/testlib blitiri.com.ar/go/chasquid/internal/tlsconst blitiri.com.ar/go/chasquid/internal/trace blitiri.com.ar/go/chasquid/internal/userdb encoding -internal/goos +internal/goarch internal/unsafeheader -internal/itoa +internal/goos internal/goexperiment -internal/goarch +internal/itoa +math/bits internal/cpu +internal/abi +runtime/internal/math +runtime/internal/sys runtime/internal/syscall -math/bits unicode/utf8 internal/race sync/atomic -internal/abi unicode -runtime/internal/math -runtime/internal/sys internal/nettrace google.golang.org/protobuf/internal/flags unicode/utf16 -math -google.golang.org/protobuf/internal/set -internal/bytealg -runtime/internal/atomic container/ring container/list +google.golang.org/protobuf/internal/set crypto/internal/subtle +math +internal/bytealg +runtime/internal/atomic crypto/subtle crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 vendor/golang.org/x/crypto/internal/subtle blitiri.com.ar/go/chasquid/internal/set runtime -internal/reflectlite sync -google.golang.org/protobuf/internal/pragma -internal/singleflight +internal/reflectlite internal/testlog +internal/singleflight +google.golang.org/protobuf/internal/pragma internal/sysinfo math/rand runtime/cgo errors sort +io internal/oserror internal/safefilepath -io +strconv path vendor/golang.org/x/net/dns/dnsmessage -strconv syscall crypto/internal/nistec/fiat golang.org/x/text/internal/tag -hash strings +hash bytes hash/fnv hash/crc32 crypto/internal/randutil -vendor/golang.org/x/text/transform -bufio -html +text/tabwriter reflect crypto crypto/rc4 -net/http/internal/ascii +vendor/golang.org/x/text/transform golang.org/x/text/transform +bufio +html +net/http/internal/ascii +golang.org/x/text/runes +golang.org/x/text/width regexp/syntax -text/tabwriter internal/syscall/unix time internal/syscall/execenv -golang.org/x/text/runes -golang.org/x/text/width regexp internal/poll -context io/fs +context embed os internal/fmtsort encoding/binary -internal/godebug -blitiri.com.ar/go/chasquid/internal/safeio -io/ioutil -path/filepath -os/signal -fmt -internal/intern -google.golang.org/protobuf/internal/detrand encoding/base64 crypto/cipher -crypto/internal/nistec -net/netip crypto/internal/edwards25519/field crypto/md5 vendor/golang.org/x/crypto/internal/poly1305 +crypto/internal/nistec +vendor/golang.org/x/crypto/curve25519/internal/field encoding/pem crypto/internal/edwards25519 +golang.org/x/sys/unix crypto/internal/boring crypto/des -vendor/golang.org/x/crypto/chacha20 -vendor/golang.org/x/crypto/curve25519/internal/field +fmt +internal/godebug +path/filepath +internal/intern +google.golang.org/protobuf/internal/detrand +io/ioutil +net/netip +blitiri.com.ar/go/chasquid/internal/safeio crypto/aes crypto/sha512 -net crypto/hmac crypto/sha1 crypto/sha256 +vendor/golang.org/x/crypto/chacha20 +vendor/golang.org/x/crypto/hkdf +os/exec +golang.org/x/crypto/pbkdf2 +os/signal +golang.org/x/crypto/scrypt vendor/golang.org/x/crypto/chacha20poly1305 +net flag log google.golang.org/protobuf/internal/errors @@ -512,111 +535,106 @@ encoding/json google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/internal/version +google.golang.org/protobuf/reflect/protoreflect net/url text/template/parse -google.golang.org/protobuf/reflect/protoreflect math/big +compress/gzip encoding/hex vendor/golang.org/x/crypto/curve25519 -compress/gzip -vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/net/http2/hpack 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/reflect/protoregistry -google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/internal/descfmt +google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/internal/descopts text/template vendor/golang.org/x/text/secure/bidirule -mime +vendor/golang.org/x/net/http2/hpack google.golang.org/protobuf/proto +mime +google.golang.org/protobuf/internal/encoding/defval mime/quotedprintable net/http/internal -google.golang.org/protobuf/internal/encoding/defval blitiri.com.ar/go/chasquid/internal/envelope -vendor/golang.org/x/net/idna golang.org/x/text/unicode/bidi +golang.org/x/text/unicode/norm +golang.org/x/text/internal/language crypto/rand -crypto/elliptic -crypto/internal/boring/bbig -encoding/asn1 google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/internal/filedesc +crypto/elliptic html/template +crypto/internal/boring/bbig +encoding/asn1 crypto/ed25519 +blitiri.com.ar/go/chasquid/internal/protoio crypto/rsa crypto/dsa +golang.org/x/text/secure/bidirule +vendor/golang.org/x/net/idna +golang.org/x/text/internal/language/compact vendor/golang.org/x/crypto/cryptobyte -blitiri.com.ar/go/chasquid/internal/protoio crypto/x509/pkix -golang.org/x/text/secure/bidirule -os/exec -golang.org/x/text/unicode/norm +google.golang.org/protobuf/internal/encoding/tag blitiri.com.ar/go/chasquid/internal/tlsconst -golang.org/x/text/internal/language -golang.org/x/crypto/pbkdf2 -golang.org/x/crypto/scrypt +golang.org/x/net/idna +golang.org/x/text/language internal/profile -crypto/ecdsa runtime/pprof +google.golang.org/protobuf/internal/impl +crypto/ecdsa runtime/trace runtime/debug -golang.org/x/sys/unix -google.golang.org/protobuf/internal/encoding/tag -testing -golang.org/x/text/internal/language/compact -google.golang.org/protobuf/internal/impl -golang.org/x/text/language -golang.org/x/net/idna +golang.org/x/term golang.org/x/text/internal golang.org/x/text/cases -log/syslog -blitiri.com.ar/go/chasquid/internal/haproxy +testing +golang.org/x/text/secure/precis +blitiri.com.ar/go/chasquid/internal/normalize net/textproto -vendor/golang.org/x/net/http/httpproxy +log/syslog crypto/x509 +vendor/golang.org/x/net/http/httpproxy +blitiri.com.ar/go/chasquid/internal/haproxy blitiri.com.ar/go/spf blitiri.com.ar/go/systemd -golang.org/x/text/secure/precis blitiri.com.ar/go/log -golang.org/x/term vendor/golang.org/x/net/http/httpguts mime/multipart blitiri.com.ar/go/chasquid/internal/dovecot net/mail blitiri.com.ar/go/chasquid/cmd/mda-lmtp -blitiri.com.ar/go/chasquid/internal/normalize -crypto/tls google.golang.org/protobuf/internal/filetype +crypto/tls google.golang.org/protobuf/runtime/protoimpl +blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/userdb blitiri.com.ar/go/chasquid/internal/protoio/testpb -blitiri.com.ar/go/chasquid/internal/config blitiri.com.ar/go/chasquid/internal/testlib net/http/httptrace net/smtp net/http blitiri.com.ar/go/chasquid/internal/smtp -golang.org/x/net/context/ctxhttp expvar +golang.org/x/net/context/ctxhttp net/http/pprof blitiri.com.ar/go/chasquid/internal/nettrace blitiri.com.ar/go/chasquid/internal/expvarom blitiri.com.ar/go/chasquid/internal/trace blitiri.com.ar/go/chasquid/internal/maillog -blitiri.com.ar/go/chasquid/internal/auth blitiri.com.ar/go/chasquid/internal/domaininfo -blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/internal/sts -blitiri.com.ar/go/chasquid/cmd/chasquid-util +blitiri.com.ar/go/chasquid/internal/auth +blitiri.com.ar/go/chasquid/internal/aliases blitiri.com.ar/go/chasquid/cmd/smtp-check blitiri.com.ar/go/chasquid/internal/courier +blitiri.com.ar/go/chasquid/cmd/chasquid-util blitiri.com.ar/go/chasquid/internal/queue blitiri.com.ar/go/chasquid/internal/smtpsrv blitiri.com.ar/go/chasquid @@ -642,7 +660,7 @@ === RUN TestTooMuchRecursionOnCatchAll --- PASS: TestTooMuchRecursionOnCatchAll (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.00s) +--- PASS: TestAddFile (0.01s) === RUN TestRichFile --- PASS: TestRichFile (0.00s) === RUN TestManyFiles @@ -665,7 +683,7 @@ --- PASS: FuzzReader/c9c80ba9f513841cb081fe9bb7439d36f9f7a06bb999d4c39441991ccc878a9e (0.00s) --- PASS: FuzzReader/d40a98862ed393eb712e47a91bcef18e6f24cf368bb4bd248c7a7101ef8e178d (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/aliases 0.105s +ok blitiri.com.ar/go/chasquid/internal/aliases 0.117s === 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 @@ -678,11 +696,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.79s) +--- PASS: TestAuthenticate (1.29s) === RUN TestInterfaces --- PASS: TestInterfaces (0.00s) === RUN TestMultipleBackends ---- PASS: TestMultipleBackends (0.20s) +--- PASS: TestMultipleBackends (0.21s) === RUN TestErrors --- PASS: TestErrors (0.00s) === RUN TestReload @@ -711,28 +729,28 @@ --- PASS: FuzzDecodeResponse/d9aa9c617d1f5b3021aca758b9d896d136e3b16ed53233d02abffd02aa73ffa4 (0.00s) --- PASS: FuzzDecodeResponse/de05c7993312bab83e8114e9d9ced331c49822dc55c1a353f1cc9718a28226e7 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/auth 1.099s +ok blitiri.com.ar/go/chasquid/internal/auth 1.615s === RUN TestEmptyStruct --- PASS: TestEmptyStruct (0.00s) === RUN TestEmptyConfig - testlib.go:32: test directory: "/tmp/testlib_1545828712" + testlib.go:32: test directory: "/tmp/testlib_2544472109" --- PASS: TestEmptyConfig (0.00s) === RUN TestFullConfig - testlib.go:32: test directory: "/tmp/testlib_4192849609" + testlib.go:32: test directory: "/tmp/testlib_4140771802" --- PASS: TestFullConfig (0.00s) === RUN TestErrorLoading --- PASS: TestErrorLoading (0.00s) === RUN TestBrokenConfig - testlib.go:32: test directory: "/tmp/testlib_1814384882" + testlib.go:32: test directory: "/tmp/testlib_2798065965" --- PASS: TestBrokenConfig (0.00s) === RUN TestBrokenOverride - testlib.go:32: test directory: "/tmp/testlib_3822320655" + testlib.go:32: test directory: "/tmp/testlib_4162654487" --- PASS: TestBrokenOverride (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/config 0.015s +ok blitiri.com.ar/go/chasquid/internal/config 0.037s === RUN TestMDA - testlib.go:32: test directory: "/tmp/testlib_2103418752" ---- PASS: TestMDA (0.02s) + testlib.go:32: test directory: "/tmp/testlib_382323082" +--- PASS: TestMDA (0.01s) === RUN TestMDATimeout _ mda.go:67 Courier.MDA to@local: error: operation timed out --- PASS: TestMDATimeout (0.11s) @@ -746,10 +764,10 @@ === RUN TestSanitize --- PASS: TestSanitize (0.00s) === RUN TestSMTP - testlib.go:32: test directory: "/tmp/testlib_1077768755" - testlib.go:32: test directory: "/tmp/testlib_660755271" -_ smtp.go:125 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::43855: too many colons in address -_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::43855: too many colons in address + testlib.go:32: test directory: "/tmp/testlib_1996378478" + testlib.go:32: test directory: "/tmp/testlib_4113414172" +_ smtp.go:125 Courier.SMTP to@to: error: Could not dial: dial tcp: address ::::38389: too many colons in address +_ smtp.go:101 Courier.SMTP to@to: error: \":::\" returned transient error: Could not dial: dial tcp: address ::::38389: too many colons in address fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -757,26 +775,26 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestSMTP (0.09s) +--- PASS: TestSMTP (0.19s) === RUN TestSMTPErrors - testlib.go:32: test directory: "/tmp/testlib_1244883129" - testlib.go:32: test directory: "/tmp/testlib_2811526327" + testlib.go:32: test directory: "/tmp/testlib_2873817382" + testlib.go:32: test directory: "/tmp/testlib_1984273065" fakeserver_test.go:97: fakeServer got connection -_ smtp.go:136 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:54868->127.0.0.1:45495: 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:54868->127.0.0.1:45495: 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:54868->127.0.0.1:45495: 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:54868->127.0.0.1:45495: i/o timeout) +_ smtp.go:136 Courier.SMTP to@to: error: Error saying hello: write tcp 127.0.0.1:54750->127.0.0.1:39505: 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:54750->127.0.0.1:39505: 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:54750->127.0.0.1:39505: 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:54750->127.0.0.1:39505: i/o timeout) fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_3199101761" - testlib.go:32: test directory: "/tmp/testlib_3645203033" + testlib.go:32: test directory: "/tmp/testlib_3167715402" + testlib.go:32: test directory: "/tmp/testlib_1941945422" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" _ smtp.go:187 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:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_694378604" - testlib.go:32: test directory: "/tmp/testlib_2354135739" + testlib.go:32: test directory: "/tmp/testlib_958496630" + testlib.go:32: test directory: "/tmp/testlib_3202173926" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -784,8 +802,8 @@ _ smtp.go:187 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:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_2010490220" - testlib.go:32: test directory: "/tmp/testlib_3731155484" + testlib.go:32: test directory: "/tmp/testlib_3663357468" + testlib.go:32: test directory: "/tmp/testlib_1984961119" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -794,8 +812,8 @@ _ smtp.go:192 Courier.SMTP to@to: error: DATA 554 data error smtp_test.go:140: failed as expected: DATA 554 data error fakeserver_test.go:131: fakeServer exiting: unexpected EOF - testlib.go:32: test directory: "/tmp/testlib_1338201122" - testlib.go:32: test directory: "/tmp/testlib_3072651962" + testlib.go:32: test directory: "/tmp/testlib_3635577097" + testlib.go:32: test directory: "/tmp/testlib_3757896380" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "MAIL FROM:" @@ -804,9 +822,9 @@ _ smtp.go:201 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:104: fakeServer exiting: EOF ---- PASS: TestSMTPErrors (1.42s) +--- PASS: TestSMTPErrors (1.50s) === RUN TestNoMXServer - testlib.go:32: test directory: "/tmp/testlib_710939064" + testlib.go:32: test directory: "/tmp/testlib_4163177623" _ 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) @@ -821,8 +839,8 @@ === RUN TestLookupInvalidDomain --- PASS: TestLookupInvalidDomain (0.00s) === RUN TestTLS - testlib.go:32: test directory: "/tmp/testlib_710767962" - testlib.go:32: test directory: "/tmp/testlib_3475190203" + testlib.go:32: test directory: "/tmp/testlib_3052831865" + testlib.go:32: test directory: "/tmp/testlib_2691624583" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -833,7 +851,7 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_11649713" + testlib.go:32: test directory: "/tmp/testlib_2506837138" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" _ domaininfo.go:144 DomainInfo.Outgoing to: error: to outgoing denied: PLAIN < TLS_INSECURE @@ -841,21 +859,21 @@ _ 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:104: fakeServer exiting: EOF ---- PASS: TestTLS (0.10s) +--- PASS: TestTLS (0.40s) === RUN TestTLSError - testlib.go:32: test directory: "/tmp/testlib_3164144082" - testlib.go:32: test directory: "/tmp/testlib_3902581317" + testlib.go:32: test directory: "/tmp/testlib_2941074323" + testlib.go:32: test directory: "/tmp/testlib_3129633033" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" _ smtp.go:159 Courier.SMTP to@to: error: TLS error: 500 starttls err - fakeserver_test.go:104: fakeServer exiting: EOF _ smtp.go:101 Courier.SMTP to@to: error: \"localhost\" returned transient error: TLS error: 500 starttls err _ smtp.go:105 Courier.SMTP to@to: error: all MXs returned transient failures (last: TLS error: 500 starttls err) ---- PASS: TestTLSError (0.09s) + fakeserver_test.go:104: fakeServer exiting: EOF +--- PASS: TestTLSError (0.17s) === RUN TestSTSPolicyEnforcement - testlib.go:32: test directory: "/tmp/testlib_3507199311" - testlib.go:32: test directory: "/tmp/testlib_2541377325" + testlib.go:32: test directory: "/tmp/testlib_1070084655" + testlib.go:32: test directory: "/tmp/testlib_2938459916" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -863,7 +881,7 @@ _ domaininfo.go:152 DomainInfo.Outgoing to: to outgoing level raised: TLS_INSECURE > PLAIN _ smtp.go:179 test test: error: invalid security level (TLS_INSECURE) for STS policy fakeserver_test.go:104: fakeServer exiting: EOF - testlib.go:32: test directory: "/tmp/testlib_1028167176" + testlib.go:32: test directory: "/tmp/testlib_2319653391" fakeserver_test.go:97: fakeServer got connection fakeserver_test.go:108: fakeServer read: "EHLO hello" fakeserver_test.go:108: fakeServer read: "STARTTLS" @@ -874,24 +892,24 @@ fakeserver_test.go:108: fakeServer read: "DATA" fakeserver_test.go:108: fakeServer read: "QUIT" fakeserver_test.go:104: fakeServer exiting: EOF ---- PASS: TestSTSPolicyEnforcement (0.11s) +--- PASS: TestSTSPolicyEnforcement (0.26s) PASS -ok blitiri.com.ar/go/chasquid/internal/courier 2.048s +ok blitiri.com.ar/go/chasquid/internal/courier 2.763s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1736255733" + testlib.go:32: test directory: "/tmp/testlib_3466326783" _ domaininfo.go:116 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:108 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:108 DomainInfo.Incoming d1: error: d1 incoming denied: TLS_INSECURE < TLS_SECURE --- PASS: TestBasic (0.00s) === RUN TestNewDomain - testlib.go:32: test directory: "/tmp/testlib_770594312" + testlib.go:32: test directory: "/tmp/testlib_4148715585" _ domaininfo.go:116 DomainInfo.Incoming insecure: insecure incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing insecure: insecure outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:116 DomainInfo.Incoming secure: secure incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing secure: secure outgoing level raised: TLS_SECURE > PLAIN --- PASS: TestNewDomain (0.00s) === RUN TestProgressions - testlib.go:32: test directory: "/tmp/testlib_2562379713" + testlib.go:32: test directory: "/tmp/testlib_607825403" _ domaininfo.go:116 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_INSECURE > PLAIN _ domaininfo.go:152 DomainInfo.Outgoing pisis: pisis outgoing level raised: TLS_INSECURE > PLAIN _ domaininfo.go:116 DomainInfo.Incoming pisis: pisis incoming level raised: TLS_SECURE > TLS_INSECURE @@ -904,23 +922,23 @@ _ domaininfo.go:144 DomainInfo.Outgoing ssip: error: ssip outgoing denied: TLS_INSECURE < TLS_SECURE _ domaininfo.go:108 DomainInfo.Incoming ssip: error: ssip incoming denied: PLAIN < TLS_SECURE _ domaininfo.go:144 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_2696057062" + testlib.go:32: test directory: "/tmp/testlib_1943609251" _ domaininfo.go:116 DomainInfo.Incoming d1: d1 incoming level raised: TLS_SECURE > PLAIN _ domaininfo.go:67 DomainInfo.Reload reload: error: id \"d1\": proto:\u00a0syntax error (line 1:1): invalid field name: invalid-text-protobuf-contents --- PASS: TestErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.177s +ok blitiri.com.ar/go/chasquid/internal/domaininfo 0.152s === RUN TestUsernameNotSafe --- PASS: TestUsernameNotSafe (0.00s) === RUN TestAutodetect - testlib.go:32: test directory: "/tmp/testlib_1073072128" + testlib.go:32: test directory: "/tmp/testlib_925851310" --- PASS: TestAutodetect (0.00s) === RUN TestReload --- PASS: TestReload (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/dovecot 0.037s +ok blitiri.com.ar/go/chasquid/internal/dovecot 0.057s === RUN TestSplit --- PASS: TestSplit (0.00s) === RUN TestDomainIn @@ -928,13 +946,13 @@ === RUN TestAddHeader --- PASS: TestAddHeader (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/envelope 0.037s +ok blitiri.com.ar/go/chasquid/internal/envelope 0.057s === RUN TestHandler --- PASS: TestHandler (0.00s) === RUN TestMapLabelAccident --- PASS: TestMapLabelAccident (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/expvarom 0.059s +ok blitiri.com.ar/go/chasquid/internal/expvarom 0.026s === RUN TestNoNewline --- PASS: TestNoNewline (0.00s) === RUN TestBasic @@ -959,15 +977,15 @@ haproxy_test.go:69: testing 18: PROXY TCP6 5::5 6::6 7777 8888 --- PASS: TestBasic (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/haproxy 0.013s +ok blitiri.com.ar/go/chasquid/internal/haproxy 0.021s === RUN TestLogger ---- PASS: TestLogger (0.01s) +--- PASS: TestLogger (0.00s) === RUN TestDefault --- PASS: TestDefault (0.00s) === RUN TestFailedLogger --- PASS: TestFailedLogger (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/maillog 0.043s +ok blitiri.com.ar/go/chasquid/internal/maillog 0.099s === RUN TestContext --- PASS: TestContext (0.00s) === RUN TestHistogramBasic @@ -975,19 +993,19 @@ === RUN TestHistogramEmpty --- PASS: TestHistogramEmpty (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.04s) +--- PASS: TestHTTP (0.10s) === RUN TestHTTPLong ---- PASS: TestHTTPLong (0.01s) +--- PASS: TestHTTPLong (0.04s) === RUN TestHTTPErrors ---- PASS: TestHTTPErrors (0.05s) +--- PASS: TestHTTPErrors (0.15s) === RUN TestHTTPUroboro ---- PASS: TestHTTPUroboro (0.03s) +--- PASS: TestHTTPUroboro (0.09s) === RUN TestHTTPDeep ---- PASS: TestHTTPDeep (0.02s) +--- PASS: TestHTTPDeep (0.09s) === RUN TestStripZeros --- PASS: TestStripZeros (0.00s) === RUN TestRegisterHandler ---- PASS: TestRegisterHandler (0.01s) +--- PASS: TestRegisterHandler (0.04s) === RUN TestBasic --- PASS: TestBasic (0.00s) === RUN TestLong @@ -1003,7 +1021,7 @@ === RUN TestFindParent --- PASS: TestFindParent (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/nettrace 0.272s +ok blitiri.com.ar/go/chasquid/internal/nettrace 0.597s === RUN TestUser --- PASS: TestUser (0.00s) === RUN TestDomain @@ -1061,41 +1079,41 @@ --- PASS: FuzzDomainToUnicode/d8637022b61fb5c4df4e153063564accd6331debaafdd594405c320a5e9f2e70 (0.00s) --- PASS: FuzzDomainToUnicode/dc0204d8e2ab058a763873d2a5fede806e95235771ecdd96b56c906886822c19 (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/normalize 0.008s +ok blitiri.com.ar/go/chasquid/internal/normalize 0.023s === RUN TestBin - testlib.go:32: test directory: "/tmp/testlib_1267329461" + testlib.go:32: test directory: "/tmp/testlib_2082787460" --- PASS: TestBin (0.00s) === RUN TestText - testlib.go:32: test directory: "/tmp/testlib_3931974836" ---- PASS: TestText (0.01s) + testlib.go:32: test directory: "/tmp/testlib_2975542328" +--- PASS: TestText (0.00s) === RUN TestStore - testlib.go:32: test directory: "/tmp/testlib_767441918" ---- PASS: TestStore (0.00s) + testlib.go:32: test directory: "/tmp/testlib_3234347710" +--- PASS: TestStore (0.02s) === RUN TestFileErrors - testlib.go:32: test directory: "/tmp/testlib_2136198093" + testlib.go:32: test directory: "/tmp/testlib_2158332508" --- PASS: TestFileErrors (0.00s) === RUN TestMarshalErrors - testlib.go:32: test directory: "/tmp/testlib_2042990966" + testlib.go:32: test directory: "/tmp/testlib_3775849536" --- PASS: TestMarshalErrors (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/protoio 0.053s +ok blitiri.com.ar/go/chasquid/internal/protoio 0.077s ? blitiri.com.ar/go/chasquid/internal/protoio/testpb [no test files] === RUN TestDSN dsn_test.go:49: From: Mail Delivery System To: Subject: Mail delivery failed: returning message to sender - Message-ID: - Date: Tue, 14 May 2024 08:14:36 -1200 + Message-ID: + Date: Thu, 13 Apr 2023 03:55:35 +1400 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="s7Ol5ptmOkY" + boundary="RPZ_SDXc2Wk" - --s7Ol5ptmOkY + --RPZ_SDXc2Wk Content-Type: text/plain; charset="utf-8" Content-Disposition: inline Content-Description: Notification @@ -1120,7 +1138,7 @@ oh! the humanity! - --s7Ol5ptmOkY + --RPZ_SDXc2Wk Content-Type: message/global-delivery-status Content-Description: Delivery Report Content-Transfer-Encoding: 8bit @@ -1151,7 +1169,7 @@ - --s7Ol5ptmOkY + --RPZ_SDXc2Wk Content-Type: message/rfc822 Content-Description: Undelivered Message Content-Transfer-Encoding: 8bit @@ -1161,75 +1179,73 @@ Data ñaca. - --s7Ol5ptmOkY-- + --RPZ_SDXc2Wk-- --- PASS: TestDSN (0.00s) === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_1478866959" -_ queue.go:308 Queue.SendLoop TC9lJDWDrXc: from from -_ queue.go:367 Queue.SendLoop TC9lJDWDrXc: nodomain sent -_ queue.go:367 Queue.SendLoop TC9lJDWDrXc: am@loco sent -_ queue.go:367 Queue.SendLoop TC9lJDWDrXc: x@remote sent -_ queue.go:342 Queue.SendLoop TC9lJDWDrXc: all done + testlib.go:32: test directory: "/tmp/testlib_1160614953" +_ queue.go:308 Queue.SendLoop yTmLXU5qq0o: from from +_ queue.go:367 Queue.SendLoop yTmLXU5qq0o: nodomain sent +_ queue.go:367 Queue.SendLoop yTmLXU5qq0o: am@loco sent +_ queue.go:367 Queue.SendLoop yTmLXU5qq0o: x@remote sent +_ queue.go:342 Queue.SendLoop yTmLXU5qq0o: all done --- PASS: TestBasic (0.00s) === RUN TestDSNOnTimeout - testlib.go:32: test directory: "/tmp/testlib_3401700853" -_ queue.go:308 Queue.SendLoop JFArt0fmRt0: from from@loco -_ queue.go:465 Queue.SendLoop JFArt0fmRt0: queued DSN: AxC6vzRKMVU -_ queue.go:342 Queue.SendLoop JFArt0fmRt0: all done -_ queue.go:308 Queue.SendLoop AxC6vzRKMVU: from <> -_ queue.go:367 Queue.SendLoop AxC6vzRKMVU: from@loco sent -_ queue.go:342 Queue.SendLoop AxC6vzRKMVU: all done + testlib.go:32: test directory: "/tmp/testlib_4273779024" +_ queue.go:308 Queue.SendLoop rwGsX7rA60A: from from@loco +_ queue.go:465 Queue.SendLoop rwGsX7rA60A: queued DSN: tgTWVffjlXI +_ queue.go:342 Queue.SendLoop rwGsX7rA60A: all done +_ queue.go:308 Queue.SendLoop tgTWVffjlXI: from <> +_ queue.go:367 Queue.SendLoop tgTWVffjlXI: from@loco sent +_ queue.go:342 Queue.SendLoop tgTWVffjlXI: all done --- PASS: TestDSNOnTimeout (0.00s) === RUN TestAliases - testlib.go:32: test directory: "/tmp/testlib_2991862572" -_ queue.go:308 Queue.SendLoop oFnMKM_GiaA: from from -_ queue.go:367 Queue.SendLoop oFnMKM_GiaA: ata@hualpa sent -_ queue.go:367 Queue.SendLoop oFnMKM_GiaA: pq@loco sent -_ queue.go:367 Queue.SendLoop oFnMKM_GiaA: rs@loco sent ---- PASS: TestAliases (0.00s) + testlib.go:32: test directory: "/tmp/testlib_3860960012" +_ queue.go:308 Queue.SendLoop kSFauHGsfu0: from from +_ queue.go:367 Queue.SendLoop kSFauHGsfu0: ata@hualpa sent +_ queue.go:367 Queue.SendLoop kSFauHGsfu0: pq@loco sent +_ queue.go:367 Queue.SendLoop kSFauHGsfu0: rs@loco sent +_ queue.go:342 Queue.SendLoop kSFauHGsfu0: all done +--- PASS: TestAliases (0.01s) === RUN TestFullQueue -_ queue.go:375 Queue.SendLoop oFnMKM_GiaA: error: failed to write: open /tmp/testlib_2991862572/.m:oFnMKM_GiaA2962368336: no such file or directory -_ queue.go:342 Queue.SendLoop oFnMKM_GiaA: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_2991862572/m:oFnMKM_GiaA": remove /tmp/testlib_2991862572/m:oFnMKM_GiaA: no such file or directory - testlib.go:32: test directory: "/tmp/testlib_1016578123" + testlib.go:32: test directory: "/tmp/testlib_1608340315" _ queue.go:164 Queue.Put from: error: queue full --- PASS: TestFullQueue (0.00s) === RUN TestPipes - testlib.go:32: test directory: "/tmp/testlib_584548474" -_ queue.go:308 Queue.SendLoop GBYFTG2o8fY: from from -_ queue.go:367 Queue.SendLoop GBYFTG2o8fY: to sent -_ queue.go:375 Queue.SendLoop GBYFTG2o8fY: error: failed to write: open /tmp/testlib_1016578123/.m:GBYFTG2o8fY1661727938: no such file or directory -_ queue.go:342 Queue.SendLoop GBYFTG2o8fY: all done -E queue.go:230 failed to remove queue file "/tmp/testlib_1016578123/m:GBYFTG2o8fY": remove /tmp/testlib_1016578123/m:GBYFTG2o8fY: no such file or directory + testlib.go:32: test directory: "/tmp/testlib_3354545202" +_ queue.go:308 Queue.SendLoop suHfIVd-B1Q: from from +_ queue.go:367 Queue.SendLoop suHfIVd-B1Q: to sent +_ queue.go:375 Queue.SendLoop suHfIVd-B1Q: error: failed to write: open /tmp/testlib_1608340315/.m:suHfIVd-B1Q837661623: no such file or directory +_ queue.go:342 Queue.SendLoop suHfIVd-B1Q: all done +E queue.go:230 failed to remove queue file "/tmp/testlib_1608340315/m:suHfIVd-B1Q": remove /tmp/testlib_1608340315/m:suHfIVd-B1Q: no such file or directory --- PASS: TestPipes (0.01s) === RUN TestBadPath --- PASS: TestBadPath (0.00s) === RUN TestNextDelay --- PASS: TestNextDelay (0.00s) === RUN TestSerialization - testlib.go:32: test directory: "/tmp/testlib_3211621743" -_ queue.go:308 Queue.SendLoop zJRmpdtsEas: from from@loco -_ queue.go:367 Queue.SendLoop zJRmpdtsEas: to@to sent -_ queue.go:342 Queue.SendLoop zJRmpdtsEas: all done + testlib.go:32: test directory: "/tmp/testlib_196309487" +_ queue.go:308 Queue.SendLoop ue7ywoRqXAQ: from from@loco +_ queue.go:367 Queue.SendLoop ue7ywoRqXAQ: to@to sent +_ queue.go:342 Queue.SendLoop ue7ywoRqXAQ: all done --- PASS: TestSerialization (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/queue 0.081s +ok blitiri.com.ar/go/chasquid/internal/queue 0.111s === RUN TestWriteFile - testlib.go:32: test directory: "/tmp/testlib_2656560062" + testlib.go:32: test directory: "/tmp/testlib_1029271970" --- PASS: TestWriteFile (0.00s) === RUN TestWriteFileWithOp - testlib.go:32: test directory: "/tmp/testlib_2449378146" + testlib.go:32: test directory: "/tmp/testlib_93681170" --- PASS: TestWriteFileWithOp (0.00s) === RUN TestWriteFileWithFailingOp - testlib.go:32: test directory: "/tmp/testlib_2807251854" + testlib.go:32: test directory: "/tmp/testlib_3742219434" --- PASS: TestWriteFileWithFailingOp (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/safeio 0.072s +ok blitiri.com.ar/go/chasquid/internal/safeio 0.025s === RUN TestString --- PASS: TestString (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/set 0.020s +ok blitiri.com.ar/go/chasquid/internal/set 0.034s === RUN TestIsPermanent --- PASS: TestIsPermanent (0.00s) === RUN TestIsASCII @@ -1243,16 +1259,17 @@ === RUN TestFallbackToIDNA --- PASS: TestFallbackToIDNA (0.00s) === RUN TestLineTooLong ---- PASS: TestLineTooLong (0.13s) +--- PASS: TestLineTooLong (0.14s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtp 0.175s -_ server.go:218 Server listening on 127.0.0.1:39647 (SMTP) -2024-05-14 08:14:36.744231 daemon listening on 127.0.0.1:39647 -_ server.go:218 Server listening on 127.0.0.1:34765 (submission) -2024-05-14 08:14:36.744441 daemon listening on 127.0.0.1:34765 -_ server.go:218 Server listening on 127.0.0.1:43327 (submission+TLS) -2024-05-14 08:14:36.744514 daemon listening on 127.0.0.1:43327 +ok blitiri.com.ar/go/chasquid/internal/smtp 0.216s +_ server.go:218 Server listening on 127.0.0.1:45053 (submission) +2023-04-13 03:55:37.004889 daemon listening on 127.0.0.1:45053 +_ server.go:218 Server listening on 127.0.0.1:41671 (submission+TLS) +2023-04-13 03:55:37.005144 daemon listening on 127.0.0.1:41671 +_ server.go:218 Server listening on 127.0.0.1:35235 (SMTP) +2023-04-13 03:55:37.005232 daemon listening on 127.0.0.1:35235 === RUN TestSecLevel +_ conn.go:189 SMTP.Conn 127.0.0.1:47022: error: error completing TLS handshake: EOF _ domaininfo.go:116 DomainInfo.Incoming slc: slc incoming level raised: TLS_CLIENT > PLAIN _ domaininfo.go:108 DomainInfo.Incoming slc: error: slc incoming denied: PLAIN < TLS_CLIENT _ conn.go:533 testconn testconn: error: security level check for slc failed (PLAIN) @@ -1266,7 +1283,6 @@ conn_test.go:111: testing ".\r\n" conn_test.go:111: testing "\r\n." conn_test.go:111: testing "\r\n.\r\n" -_ conn.go:189 SMTP.Conn 127.0.0.1:48480: error: error completing TLS handshake: EOF conn_test.go:111: testing ".\n" conn_test.go:111: testing "\n." conn_test.go:111: testing "\n.\n" @@ -1292,237 +1308,237 @@ === RUN TestSanitizeEHLODomain --- PASS: TestSanitizeEHLODomain (0.00s) === RUN TestSimple -_ conn.go:684 SMTP.Conn 127.0.0.1:45874: Queued from from@from to [to@localhost] - lFUXUYt33OA -2024-05-14 08:14:36.858049 lFUXUYt33OA from=from@from queued ip=127.0.0.1:45874 to=[to@localhost] -_ queue.go:308 Queue.SendLoop lFUXUYt33OA: from from@from -_ queue.go:367 Queue.SendLoop lFUXUYt33OA: testuser@localhost sent -2024-05-14 08:14:36.858363 lFUXUYt33OA from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop lFUXUYt33OA: all done -2024-05-14 08:14:36.858744 lFUXUYt33OA from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:44412: Queued from from@from to [to@localhost] - 96hQvmdoMek +2023-04-13 03:55:37.116984 96hQvmdoMek from=from@from queued ip=127.0.0.1:44412 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 96hQvmdoMek: from from@from +_ queue.go:367 Queue.SendLoop 96hQvmdoMek: testuser@localhost sent +2023-04-13 03:55:37.117362 96hQvmdoMek from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 96hQvmdoMek: all done +2023-04-13 03:55:37.117788 96hQvmdoMek from=from@from all done --- PASS: TestSimple (0.01s) === RUN TestSimpleTLS -_ conn.go:684 SMTP.Conn 127.0.0.1:45876: Queued from from@from to [to@localhost] - JjrFjYtdTro -2024-05-14 08:14:36.867384 JjrFjYtdTro from=from@from queued ip=127.0.0.1:45876 to=[to@localhost] -_ queue.go:308 Queue.SendLoop JjrFjYtdTro: from from@from -_ queue.go:367 Queue.SendLoop JjrFjYtdTro: testuser@localhost sent -2024-05-14 08:14:36.867623 JjrFjYtdTro from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop JjrFjYtdTro: all done -2024-05-14 08:14:36.867977 JjrFjYtdTro from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:44428: Queued from from@from to [to@localhost] - WfKzRTd4YSM +2023-04-13 03:55:37.128462 WfKzRTd4YSM from=from@from queued ip=127.0.0.1:44428 to=[to@localhost] +_ queue.go:308 Queue.SendLoop WfKzRTd4YSM: from from@from +_ queue.go:367 Queue.SendLoop WfKzRTd4YSM: testuser@localhost sent +2023-04-13 03:55:37.128726 WfKzRTd4YSM from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop WfKzRTd4YSM: all done +2023-04-13 03:55:37.129097 WfKzRTd4YSM from=from@from all done --- PASS: TestSimpleTLS (0.01s) === RUN TestManyEmails -_ conn.go:684 SMTP.Conn 127.0.0.1:45888: Queued from from@from to [to@localhost] - GXxunNy_qxY -2024-05-14 08:14:36.881149 GXxunNy_qxY from=from@from queued ip=127.0.0.1:45888 to=[to@localhost] -_ queue.go:308 Queue.SendLoop GXxunNy_qxY: from from@from -_ queue.go:367 Queue.SendLoop GXxunNy_qxY: testuser@localhost sent -2024-05-14 08:14:36.881379 GXxunNy_qxY from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop GXxunNy_qxY: all done -2024-05-14 08:14:36.881706 GXxunNy_qxY from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:45888: Queued from from@from to [to@localhost] - Srp6tyQgeTA -2024-05-14 08:14:36.883437 Srp6tyQgeTA from=from@from queued ip=127.0.0.1:45888 to=[to@localhost] -_ queue.go:308 Queue.SendLoop Srp6tyQgeTA: from from@from -_ queue.go:367 Queue.SendLoop Srp6tyQgeTA: testuser@localhost sent -2024-05-14 08:14:36.883672 Srp6tyQgeTA from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop Srp6tyQgeTA: all done -2024-05-14 08:14:36.883976 Srp6tyQgeTA from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:45888: Queued from from@from to [to@localhost] - d2w8Krkcaec -2024-05-14 08:14:36.885091 d2w8Krkcaec from=from@from queued ip=127.0.0.1:45888 to=[to@localhost] -_ queue.go:308 Queue.SendLoop d2w8Krkcaec: from from@from -_ queue.go:367 Queue.SendLoop d2w8Krkcaec: testuser@localhost sent -2024-05-14 08:14:36.885309 d2w8Krkcaec from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop d2w8Krkcaec: all done -2024-05-14 08:14:36.885599 d2w8Krkcaec from=from@from all done ---- PASS: TestManyEmails (0.02s) +_ conn.go:684 SMTP.Conn 127.0.0.1:44442: Queued from from@from to [to@localhost] - L2Tjj5kefrk +2023-04-13 03:55:37.140186 L2Tjj5kefrk from=from@from queued ip=127.0.0.1:44442 to=[to@localhost] +_ queue.go:308 Queue.SendLoop L2Tjj5kefrk: from from@from +_ queue.go:367 Queue.SendLoop L2Tjj5kefrk: testuser@localhost sent +2023-04-13 03:55:37.140355 L2Tjj5kefrk from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop L2Tjj5kefrk: all done +2023-04-13 03:55:37.140748 L2Tjj5kefrk from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:44442: Queued from from@from to [to@localhost] - mDxEqQ_4Vcg +2023-04-13 03:55:37.142168 mDxEqQ_4Vcg from=from@from queued ip=127.0.0.1:44442 to=[to@localhost] +_ queue.go:308 Queue.SendLoop mDxEqQ_4Vcg: from from@from +_ queue.go:367 Queue.SendLoop mDxEqQ_4Vcg: testuser@localhost sent +2023-04-13 03:55:37.142396 mDxEqQ_4Vcg from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop mDxEqQ_4Vcg: all done +2023-04-13 03:55:37.142710 mDxEqQ_4Vcg from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:44442: Queued from from@from to [to@localhost] - itAlygPlNco +2023-04-13 03:55:37.143719 itAlygPlNco from=from@from queued ip=127.0.0.1:44442 to=[to@localhost] +_ queue.go:308 Queue.SendLoop itAlygPlNco: from from@from +_ queue.go:367 Queue.SendLoop itAlygPlNco: testuser@localhost sent +2023-04-13 03:55:37.143924 itAlygPlNco from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop itAlygPlNco: all done +2023-04-13 03:55:37.144195 itAlygPlNco from=from@from all done +--- PASS: TestManyEmails (0.01s) === RUN TestAuth -2024-05-14 08:14:37.011932 127.0.0.1:52908 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:52908: Queued from testuser@localhost to [to@localhost] - ftUT1ZPyDzM -2024-05-14 08:14:37.013724 ftUT1ZPyDzM from=testuser@localhost queued ip=127.0.0.1:52908 to=[to@localhost] -_ queue.go:308 Queue.SendLoop ftUT1ZPyDzM: from testuser@localhost -_ queue.go:367 Queue.SendLoop ftUT1ZPyDzM: testuser@localhost sent -2024-05-14 08:14:37.014067 ftUT1ZPyDzM from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop ftUT1ZPyDzM: all done -2024-05-14 08:14:37.014433 ftUT1ZPyDzM from=testuser@localhost all done ---- PASS: TestAuth (0.13s) +2023-04-13 03:55:37.310686 127.0.0.1:53942 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:53942: Queued from testuser@localhost to [to@localhost] - zzOqctz8GZA +2023-04-13 03:55:37.315624 zzOqctz8GZA from=testuser@localhost queued ip=127.0.0.1:53942 to=[to@localhost] +_ queue.go:308 Queue.SendLoop zzOqctz8GZA: from testuser@localhost +_ queue.go:367 Queue.SendLoop zzOqctz8GZA: testuser@localhost sent +2023-04-13 03:55:37.315888 zzOqctz8GZA from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop zzOqctz8GZA: all done +2023-04-13 03:55:37.316224 zzOqctz8GZA from=testuser@localhost all done +--- PASS: TestAuth (0.17s) === RUN TestSubmissionWithoutAuth -_ conn.go:298 SMTP.Conn 127.0.0.1:52924: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated +_ conn.go:298 SMTP.Conn 127.0.0.1:53956: error: MAIL failed: 550 5.7.9 Mail to submission port must be authenticated --- PASS: TestSubmissionWithoutAuth (0.01s) === RUN TestAuthOnTLS -2024-05-14 08:14:37.143078 127.0.0.1:48494 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:48494: Queued from testuser@localhost to [to@localhost] - 5STrJeXplk4 -2024-05-14 08:14:37.144961 5STrJeXplk4 from=testuser@localhost queued ip=127.0.0.1:48494 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 5STrJeXplk4: from testuser@localhost -_ queue.go:367 Queue.SendLoop 5STrJeXplk4: testuser@localhost sent -2024-05-14 08:14:37.145273 5STrJeXplk4 from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 5STrJeXplk4: all done -2024-05-14 08:14:37.145638 5STrJeXplk4 from=testuser@localhost all done ---- PASS: TestAuthOnTLS (0.13s) +2023-04-13 03:55:37.482695 127.0.0.1:47032 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:47032: Queued from testuser@localhost to [to@localhost] - TY6wpzdWaQk +2023-04-13 03:55:37.484603 TY6wpzdWaQk from=testuser@localhost queued ip=127.0.0.1:47032 to=[to@localhost] +_ queue.go:308 Queue.SendLoop TY6wpzdWaQk: from testuser@localhost +_ queue.go:367 Queue.SendLoop TY6wpzdWaQk: testuser@localhost sent +2023-04-13 03:55:37.484870 TY6wpzdWaQk from=testuser@localhost to=testuser@localhost sent +--- PASS: TestAuthOnTLS (0.16s) === RUN TestAuthOnSMTP -2024-05-14 08:14:37.267356 127.0.0.1:45898 auth succeeded for testuser@localhost -_ conn.go:684 SMTP.Conn 127.0.0.1:45898: Queued from testuser@localhost to [to@localhost] - TgX987P9iYQ -2024-05-14 08:14:37.269182 TgX987P9iYQ from=testuser@localhost queued ip=127.0.0.1:45898 to=[to@localhost] -_ queue.go:308 Queue.SendLoop TgX987P9iYQ: from testuser@localhost -_ queue.go:367 Queue.SendLoop TgX987P9iYQ: testuser@localhost sent -2024-05-14 08:14:37.269387 TgX987P9iYQ from=testuser@localhost to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop TgX987P9iYQ: all done -2024-05-14 08:14:37.269767 TgX987P9iYQ from=testuser@localhost all done ---- PASS: TestAuthOnSMTP (0.12s) +_ queue.go:342 Queue.SendLoop TY6wpzdWaQk: all done +2023-04-13 03:55:37.485258 TY6wpzdWaQk from=testuser@localhost all done +2023-04-13 03:55:37.643040 127.0.0.1:44454 auth succeeded for testuser@localhost +_ conn.go:684 SMTP.Conn 127.0.0.1:44454: Queued from testuser@localhost to [to@localhost] - oSyUHrjrmiY +2023-04-13 03:55:37.648138 oSyUHrjrmiY from=testuser@localhost queued ip=127.0.0.1:44454 to=[to@localhost] +_ queue.go:308 Queue.SendLoop oSyUHrjrmiY: from testuser@localhost +_ queue.go:367 Queue.SendLoop oSyUHrjrmiY: testuser@localhost sent +2023-04-13 03:55:37.648344 oSyUHrjrmiY from=testuser@localhost to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop oSyUHrjrmiY: all done +2023-04-13 03:55:37.648744 oSyUHrjrmiY from=testuser@localhost all done +--- PASS: TestAuthOnSMTP (0.16s) === RUN TestBrokenAuth -_ conn.go:1075 SMTP.Conn 127.0.0.1:52936: error: error authenticating \"user\"@\"broken\": failed to auth -2024-05-14 08:14:37.385186 127.0.0.1:52936 auth failed for user@broken -_ conn.go:298 SMTP.Conn 127.0.0.1:52936: error: AUTH failed: 454 4.7.0 Temporary authentication failure -_ conn.go:298 SMTP.Conn 127.0.0.1:52936: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command +_ conn.go:1075 SMTP.Conn 127.0.0.1:53962: error: error authenticating \"user\"@\"broken\": failed to auth +2023-04-13 03:55:37.766082 127.0.0.1:53962 auth failed for user@broken +_ conn.go:298 SMTP.Conn 127.0.0.1:53962: error: AUTH failed: 454 4.7.0 Temporary authentication failure +_ conn.go:298 SMTP.Conn 127.0.0.1:53962: error: unknown<\"*\"> failed: 500 5.5.1 Unknown command --- PASS: TestBrokenAuth (0.12s) === RUN TestWrongMailParsing -_ conn.go:298 SMTP.Conn 127.0.0.1:45912: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45912: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45912: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45912: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:45924: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45924: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45924: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45924: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:45928: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45928: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45928: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45928: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:45944: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45944: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45944: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45944: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:45948: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45948: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45948: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45948: error: too many errors, breaking connection -_ conn.go:298 SMTP.Conn 127.0.0.1:45964: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:298 SMTP.Conn 127.0.0.1:45964: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:298 SMTP.Conn 127.0.0.1:45964: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:45964: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52320: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52320: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52320: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52320: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52336: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52336: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52336: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52336: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52342: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52342: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52342: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52342: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52358: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52358: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52358: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52358: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52368: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52368: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52368: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52368: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52384: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:298 SMTP.Conn 127.0.0.1:52384: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:298 SMTP.Conn 127.0.0.1:52384: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52384: error: too many errors, breaking connection --- PASS: TestWrongMailParsing (0.01s) === RUN TestNullMailFrom --- PASS: TestNullMailFrom (0.00s) === RUN TestRcptBeforeMail -_ conn.go:298 SMTP.Conn 127.0.0.1:45974: error: RCPT failed: 503 5.5.1 Sender not yet given +_ conn.go:298 SMTP.Conn 127.0.0.1:52416: error: RCPT failed: 503 5.5.1 Sender not yet given --- PASS: TestRcptBeforeMail (0.00s) === RUN TestRcptOption --- PASS: TestRcptOption (0.01s) === RUN TestRelayForbidden -2024-05-14 08:14:37.404512 127.0.0.1:45996 rejected from=from@somewhere to=[to@somewhere] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:45996: error: RCPT failed: 503 5.7.1 Relay not allowed +2023-04-13 03:55:37.786760 127.0.0.1:52442 rejected from=from@somewhere to=[to@somewhere] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52442: error: RCPT failed: 503 5.7.1 Relay not allowed --- PASS: TestRelayForbidden (0.00s) === RUN TestTooManyRecipients -2024-05-14 08:14:37.528009 127.0.0.1:52940 auth succeeded for testuser@localhost -_ conn.go:298 SMTP.Conn 127.0.0.1:52940: error: RCPT failed: 452 4.5.3 Too many recipients ---- PASS: TestTooManyRecipients (0.14s) +2023-04-13 03:55:37.935578 127.0.0.1:39146 auth succeeded for testuser@localhost +_ conn.go:298 SMTP.Conn 127.0.0.1:39146: error: RCPT failed: 452 4.5.3 Too many recipients +--- PASS: TestTooManyRecipients (0.17s) === RUN TestRcptBrokenExists -_ conn.go:596 SMTP.Conn 127.0.0.1:46010: error: error checking if user \"to@broken\" exists: failed to check if user exists -2024-05-14 08:14:37.552498 127.0.0.1:46010 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists -_ conn.go:298 SMTP.Conn 127.0.0.1:46010: error: RCPT failed: 451 4.4.3 Temporary error checking address +_ conn.go:596 SMTP.Conn 127.0.0.1:52454: error: error checking if user \"to@broken\" exists: failed to check if user exists +2023-04-13 03:55:37.967184 127.0.0.1:52454 rejected from=from@localhost to=[to@broken] - error checking if user exists: failed to check if user exists +_ conn.go:298 SMTP.Conn 127.0.0.1:52454: error: RCPT failed: 451 4.4.3 Temporary error checking address --- PASS: TestRcptBrokenExists (0.01s) === RUN TestRcptUserDoesNotExist -2024-05-14 08:14:37.557723 127.0.0.1:46020 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist -_ conn.go:298 SMTP.Conn 127.0.0.1:46020: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) +2023-04-13 03:55:37.977483 127.0.0.1:52458 rejected from=from@localhost to=[doesnotexist@localhost] - local user does not exist +_ conn.go:298 SMTP.Conn 127.0.0.1:52458: error: RCPT failed: 550 5.1.1 Destination address is unknown (user does not exist) --- PASS: TestRcptUserDoesNotExist (0.01s) === RUN TestTooMuchData -_ conn.go:684 SMTP.Conn 127.0.0.1:46034: Queued from from@from to [to@localhost] - 8DO87tnjB30 -2024-05-14 08:14:37.766437 8DO87tnjB30 from=from@from queued ip=127.0.0.1:46034 to=[to@localhost] -_ queue.go:308 Queue.SendLoop 8DO87tnjB30: from from@from -_ queue.go:367 Queue.SendLoop 8DO87tnjB30: testuser@localhost sent -2024-05-14 08:14:37.766574 8DO87tnjB30 from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop 8DO87tnjB30: all done -2024-05-14 08:14:37.797408 8DO87tnjB30 from=from@from all done -_ conn.go:684 SMTP.Conn 127.0.0.1:46034: Queued from from@from to [to@localhost] - bQl8EOe7WYQ -2024-05-14 08:14:37.958548 bQl8EOe7WYQ from=from@from queued ip=127.0.0.1:46034 to=[to@localhost] -_ queue.go:308 Queue.SendLoop bQl8EOe7WYQ: from from@from -_ queue.go:367 Queue.SendLoop bQl8EOe7WYQ: testuser@localhost sent -2024-05-14 08:14:37.958874 bQl8EOe7WYQ from=from@from to=testuser@localhost sent -_ queue.go:342 Queue.SendLoop bQl8EOe7WYQ: all done -2024-05-14 08:14:38.002168 bQl8EOe7WYQ from=from@from all done -_ conn.go:298 SMTP.Conn 127.0.0.1:46034: error: DATA failed: 552 5.3.4 Message too big -_ conn.go:684 SMTP.Conn 127.0.0.1:46034: Queued from from@from to [to@localhost] - _j8YCc29O3Y -2024-05-14 08:14:38.540959 _j8YCc29O3Y from=from@from queued ip=127.0.0.1:46034 to=[to@localhost] -_ queue.go:308 Queue.SendLoop _j8YCc29O3Y: from from@from -_ queue.go:367 Queue.SendLoop _j8YCc29O3Y: testuser@localhost sent -2024-05-14 08:14:38.541113 _j8YCc29O3Y from=from@from to=testuser@localhost sent ---- PASS: TestTooMuchData (0.98s) +_ conn.go:684 SMTP.Conn 127.0.0.1:52460: Queued from from@from to [to@localhost] - 6pG2gYxJbvc +2023-04-13 03:55:38.200273 6pG2gYxJbvc from=from@from queued ip=127.0.0.1:52460 to=[to@localhost] +_ queue.go:308 Queue.SendLoop 6pG2gYxJbvc: from from@from +_ queue.go:367 Queue.SendLoop 6pG2gYxJbvc: testuser@localhost sent +2023-04-13 03:55:38.200725 6pG2gYxJbvc from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop 6pG2gYxJbvc: all done +2023-04-13 03:55:38.248314 6pG2gYxJbvc from=from@from all done +_ conn.go:684 SMTP.Conn 127.0.0.1:52460: Queued from from@from to [to@localhost] - AzSrlM7-z8g +_ queue.go:308 Queue.SendLoop AzSrlM7-z8g: from from@from +2023-04-13 03:55:38.506165 AzSrlM7-z8g from=from@from queued ip=127.0.0.1:52460 to=[to@localhost] +_ queue.go:367 Queue.SendLoop AzSrlM7-z8g: testuser@localhost sent +2023-04-13 03:55:38.506300 AzSrlM7-z8g from=from@from to=testuser@localhost sent +_ queue.go:342 Queue.SendLoop AzSrlM7-z8g: all done +2023-04-13 03:55:38.558657 AzSrlM7-z8g from=from@from all done +_ conn.go:298 SMTP.Conn 127.0.0.1:52460: error: DATA failed: 552 5.3.4 Message too big +_ conn.go:684 SMTP.Conn 127.0.0.1:52460: Queued from from@from to [to@localhost] - OobXTYpMUDE +2023-04-13 03:55:39.200584 OobXTYpMUDE from=from@from queued ip=127.0.0.1:52460 to=[to@localhost] +_ queue.go:308 Queue.SendLoop OobXTYpMUDE: from from@from +_ queue.go:367 Queue.SendLoop OobXTYpMUDE: testuser@localhost sent +2023-04-13 03:55:39.200779 OobXTYpMUDE from=from@from to=testuser@localhost sent +--- PASS: TestTooMuchData (1.23s) === RUN TestSimpleCommands -_ conn.go:298 SMTP.Conn 127.0.0.1:46036: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. -_ conn.go:298 SMTP.Conn 127.0.0.1:46036: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. ---- PASS: TestSimpleCommands (0.01s) +_ conn.go:298 SMTP.Conn 127.0.0.1:52476: error: VRFY failed: 502 5.5.1 You have a strange feeling for a moment, then it passes. +_ conn.go:298 SMTP.Conn 127.0.0.1:52476: error: EXPN failed: 502 5.5.1 You feel disoriented for a moment. +--- PASS: TestSimpleCommands (0.00s) === RUN TestLongLines -_ conn.go:298 SMTP.Conn 127.0.0.1:46042: error: unknown<\"\"> failed: 500 5.5.1 Unknown command -_ conn.go:322 SMTP.Conn 127.0.0.1:46042: error: exiting with error: line too long ---- PASS: TestLongLines (0.00s) +_ conn.go:298 SMTP.Conn 127.0.0.1:52482: error: unknown<\"\"> failed: 500 5.5.1 Unknown command +_ conn.go:322 SMTP.Conn 127.0.0.1:52482: error: exiting with error: line too long +--- PASS: TestLongLines (0.01s) === RUN TestReset ---- PASS: TestReset (0.00s) +--- PASS: TestReset (0.01s) === RUN TestRepeatedStartTLS -_ conn.go:298 SMTP.Conn 127.0.0.1:46056: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ queue.go:342 Queue.SendLoop OobXTYpMUDE: all done +2023-04-13 03:55:39.230455 OobXTYpMUDE from=from@from all done +_ conn.go:298 SMTP.Conn 127.0.0.1:52500: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestRepeatedStartTLS (0.01s) === RUN TestStartTLSOnTLS -_ queue.go:342 Queue.SendLoop _j8YCc29O3Y: all done -2024-05-14 08:14:38.562473 _j8YCc29O3Y from=from@from all done -_ conn.go:298 SMTP.Conn 127.0.0.1:48502: error: STARTTLS failed: 503 5.5.1 You are already wearing that! +_ conn.go:298 SMTP.Conn 127.0.0.1:52634: error: STARTTLS failed: 503 5.5.1 You are already wearing that! --- PASS: TestStartTLSOnTLS (0.01s) === RUN FuzzConnection === RUN FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 -_ conn.go:298 SMTP.Conn 127.0.0.1:46058: error: RCPT failed: 500 5.5.2 Unknown command -_ conn.go:298 SMTP.Conn 127.0.0.1:46058: error: RCPT failed: 500 5.5.4 Malformed command: EOF -_ conn.go:298 SMTP.Conn 127.0.0.1:46058: error: RCPT failed: 501 5.1.3 Malformed destination address -_ conn.go:305 SMTP.Conn 127.0.0.1:46058: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52504: error: RCPT failed: 500 5.5.2 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:52504: error: RCPT failed: 500 5.5.4 Malformed command: EOF +_ conn.go:298 SMTP.Conn 127.0.0.1:52504: error: RCPT failed: 501 5.1.3 Malformed destination address +_ conn.go:305 SMTP.Conn 127.0.0.1:52504: error: too many errors, breaking connection === RUN FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 -_ conn.go:298 SMTP.Conn 127.0.0.1:46066: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! -_ conn.go:298 SMTP.Conn 127.0.0.1:46066: error: DATA failed: 503 5.5.1 Sender not yet given +_ conn.go:298 SMTP.Conn 127.0.0.1:52520: error: DATA failed: 503 5.5.1 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:52520: error: DATA failed: 503 5.5.1 Sender not yet given === RUN FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f -2024-05-14 08:14:38.569872 127.0.0.1:46066 rejected from=a@b to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:46066: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:305 SMTP.Conn 127.0.0.1:46066: error: too many errors, breaking connection -2024-05-14 08:14:38.570405 127.0.0.1:46082 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:46082: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:46082: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:298 SMTP.Conn 127.0.0.1:46082: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:305 SMTP.Conn 127.0.0.1:46082: error: too many errors, breaking connection +2023-04-13 03:55:39.252124 127.0.0.1:52520 rejected from=a@b to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52520: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:305 SMTP.Conn 127.0.0.1:52520: error: too many errors, breaking connection +2023-04-13 03:55:39.252536 127.0.0.1:52536 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52536: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52536: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:298 SMTP.Conn 127.0.0.1:52536: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:305 SMTP.Conn 127.0.0.1:52536: error: too many errors, breaking connection === RUN FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe -_ conn.go:298 SMTP.Conn 127.0.0.1:46094: error: MAIL failed: 500 5.5.2 Unknown command -_ conn.go:298 SMTP.Conn 127.0.0.1:46094: error: MAIL failed: 500 5.5.4 Malformed command: EOF -_ conn.go:298 SMTP.Conn 127.0.0.1:46094: error: MAIL failed: 501 5.1.7 Sender address malformed -_ conn.go:305 SMTP.Conn 127.0.0.1:46094: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52548: error: MAIL failed: 500 5.5.2 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:52548: error: MAIL failed: 500 5.5.4 Malformed command: EOF +_ conn.go:298 SMTP.Conn 127.0.0.1:52548: error: MAIL failed: 501 5.1.7 Sender address malformed +_ conn.go:305 SMTP.Conn 127.0.0.1:52548: error: too many errors, breaking connection === RUN FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 -_ conn.go:298 SMTP.Conn 127.0.0.1:46110: error: HELO failed: 501 Invisible customers are not welcome! -_ conn.go:298 SMTP.Conn 127.0.0.1:46110: error: EHLO failed: 501 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:52564: error: HELO failed: 501 Invisible customers are not welcome! +_ conn.go:298 SMTP.Conn 127.0.0.1:52564: error: EHLO failed: 501 Invisible customers are not welcome! === RUN FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf === RUN FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 -2024-05-14 08:14:38.577324 127.0.0.1:46122 rejected from=<> to=[user@testserver] - relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:46122: error: RCPT failed: 503 5.7.1 Relay not allowed -_ conn.go:298 SMTP.Conn 127.0.0.1:46122: error: DATA failed: 503 5.5.1 Need an address to send to -_ conn.go:298 SMTP.Conn 127.0.0.1:46122: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command -_ conn.go:305 SMTP.Conn 127.0.0.1:46122: error: too many errors, breaking connection +2023-04-13 03:55:39.264312 127.0.0.1:52590 rejected from=<> to=[user@testserver] - relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52590: error: RCPT failed: 503 5.7.1 Relay not allowed +_ conn.go:298 SMTP.Conn 127.0.0.1:52590: error: DATA failed: 503 5.5.1 Need an address to send to +_ conn.go:298 SMTP.Conn 127.0.0.1:52590: error: unknown<\"FROM:\"> failed: 500 5.5.1 Unknown command +_ conn.go:305 SMTP.Conn 127.0.0.1:52590: error: too many errors, breaking connection === RUN FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 -_ conn.go:298 SMTP.Conn 127.0.0.1:46128: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:298 SMTP.Conn 127.0.0.1:46128: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:298 SMTP.Conn 127.0.0.1:46128: error: AUTH failed: 503 5.7.10 You feel vulnerable -_ conn.go:305 SMTP.Conn 127.0.0.1:46128: error: too many errors, breaking connection +_ conn.go:298 SMTP.Conn 127.0.0.1:52592: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:52592: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:52592: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:305 SMTP.Conn 127.0.0.1:52592: error: too many errors, breaking connection === RUN FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded -_ conn.go:298 SMTP.Conn 127.0.0.1:46130: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command +_ conn.go:298 SMTP.Conn 127.0.0.1:52608: error: unknown<\"WHATIS\"> failed: 500 5.5.1 Unknown command === RUN FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 -_ conn.go:298 SMTP.Conn 127.0.0.1:46140: error: AUTH failed: 503 5.7.10 You feel vulnerable +_ conn.go:298 SMTP.Conn 127.0.0.1:52610: error: AUTH failed: 503 5.7.10 You feel vulnerable === RUN FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c -_ conn.go:298 SMTP.Conn 127.0.0.1:48508: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage -_ conn.go:298 SMTP.Conn 127.0.0.1:48508: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 -2024-05-14 08:14:38.699303 127.0.0.1:48508 auth failed for user@testserver -_ conn.go:298 SMTP.Conn 127.0.0.1:48508: error: AUTH failed: 535 5.7.8 Incorrect user or password -_ conn.go:305 SMTP.Conn 127.0.0.1:48508: error: too many errors, breaking connection ---- PASS: FuzzConnection (0.13s) +_ conn.go:298 SMTP.Conn 127.0.0.1:52648: error: AUTH failed: 534 5.7.9 Asmodeus demands 534 zorkmids for safe passage +_ conn.go:298 SMTP.Conn 127.0.0.1:52648: error: AUTH failed: 501 5.5.2 Error decoding AUTH response: illegal base64 data at input byte 28 +2023-04-13 03:55:39.393430 127.0.0.1:52648 auth failed for user@testserver +_ conn.go:298 SMTP.Conn 127.0.0.1:52648: error: AUTH failed: 535 5.7.8 Incorrect user or password +_ conn.go:305 SMTP.Conn 127.0.0.1:52648: error: too many errors, breaking connection +--- PASS: FuzzConnection (0.15s) --- PASS: FuzzConnection/3d7e992212e817da7afdb7a4e769ceec1d4047a2e630bec4b35ecd4d55560424 (0.00s) --- PASS: FuzzConnection/68d8c7b5f149996ffd46ad9a15852165d8c1cbd6c03cceb9382e5add16415c94 (0.00s) --- PASS: FuzzConnection/79e51b30c215fb19a29855deebf2ed8299b35ca6f14db9681ee504e216c44a7f (0.00s) - --- PASS: FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe (0.00s) + --- PASS: FuzzConnection/83ab02fccf91c1b9c0c972de745dc2a45d23dc3236f9027e605c3e017d8898fe (0.01s) --- PASS: FuzzConnection/a24124ade554d7a25de538f2cbbced6245ba60e90d221e51590456e222c80359 (0.00s) --- PASS: FuzzConnection/b896b41db27f6e36e4e727ac4f7b3d02fad34d217855c0d433ea3a325951b3bf (0.00s) --- PASS: FuzzConnection/bf15e6fb937795251090940ac60a37705b36a13e71a9557e7aaf0618ea2cf661 (0.00s) --- PASS: FuzzConnection/d1b1ccbbb380c53282cc2689c4bd9ff0d03a03698e9be55371739ef95d7dd671 (0.00s) --- PASS: FuzzConnection/dc70e53325976a3a1067feb0b0c956c5a9abec1c867f8198808ccff83f594ded (0.00s) --- PASS: FuzzConnection/e7682fde78ce0d78ddc7a818f151b6f04466a2c122197a2e4e8048d194ed72c2 (0.00s) - --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.12s) + --- PASS: FuzzConnection/fd41d0c11b1bb7f89825934b2ec51db1df166e34b4610e8089549eedf2e3635c (0.13s) PASS -ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.182s +ok blitiri.com.ar/go/chasquid/internal/smtpsrv 2.833s === RUN TestParsePolicy sts_test.go:90: pol1: &{Version:STSv1 Mode:enforce MXs:[*.mail.example.com] MaxAge:34h17m36s} --- PASS: TestParsePolicy (0.00s) @@ -1537,34 +1553,34 @@ sts_test.go:210: policy404: got error as expected: HTTP response status code: 404 sts_test.go:218: version99: got expected error: unknown policy version sts_test.go:226: domErr: got expected error: error for testing purposes ---- PASS: TestFetch (0.01s) +--- PASS: TestFetch (0.00s) === RUN TestPolicyTooBig sts_test.go:242: got error as expected: MTA-STS TXT record missing --- PASS: TestPolicyTooBig (0.06s) === RUN TestCacheBasics - testlib.go:32: test directory: "/tmp/testlib_2983314241" + testlib.go:32: test directory: "/tmp/testlib_3278102857" sts_test.go:277: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:286: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:300: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:309: cache fetched domErr: ---- PASS: TestCacheBasics (0.00s) +--- PASS: TestCacheBasics (0.01s) === RUN TestCacheBadData - testlib.go:32: test directory: "/tmp/testlib_4121987467" + testlib.go:32: test directory: "/tmp/testlib_384653097" sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:349: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:363: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} sts_test.go:372: cache fetched domain.com: &{STSv1 enforce [*.mail.domain.com] 1h0m0s} ---- PASS: TestCacheBadData (0.01s) +--- PASS: TestCacheBadData (0.00s) === RUN TestCacheRefresh - testlib.go:32: test directory: "/tmp/testlib_3715906164" + testlib.go:32: test directory: "/tmp/testlib_1325089125" 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_3258440435" + testlib.go:32: test directory: "/tmp/testlib_4177268666" sts_test.go:464: recovered: domain contains slash --- PASS: TestCacheSlashSafe (0.00s) === RUN TestURLForDomain @@ -1574,9 +1590,9 @@ === RUN TestHTTPGet --- PASS: TestHTTPGet (0.01s) PASS -ok blitiri.com.ar/go/chasquid/internal/sts 0.229s +ok blitiri.com.ar/go/chasquid/internal/sts 0.151s === RUN TestBasic - testlib.go:32: test directory: "/tmp/testlib_3559157827" + testlib.go:32: test directory: "/tmp/testlib_544461488" --- PASS: TestBasic (0.00s) === RUN TestRemoveCheck testlib_test.go:33: recovered: invalid/dangerous directory @@ -1587,52 +1603,52 @@ testlib_test.go:60: recovered: invalid/dangerous path --- PASS: TestRewriteSafeguard (0.00s) === RUN TestRewrite - testlib.go:32: test directory: "/tmp/testlib_2382496930" + testlib.go:32: test directory: "/tmp/testlib_1869128814" --- 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_542626263" ---- PASS: TestGenerateCert (0.16s) + testlib.go:32: test directory: "/tmp/testlib_502820986" +--- PASS: TestGenerateCert (0.07s) === RUN TestGenerateCertBadDir ---- PASS: TestGenerateCertBadDir (0.09s) +--- PASS: TestGenerateCertBadDir (0.12s) PASS -ok blitiri.com.ar/go/chasquid/internal/testlib 0.320s +ok blitiri.com.ar/go/chasquid/internal/testlib 0.272s === RUN TestVersionName --- PASS: TestVersionName (0.00s) === RUN TestCipherSuiteName --- PASS: TestCipherSuiteName (0.00s) PASS -ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.056s +ok blitiri.com.ar/go/chasquid/internal/tlsconst 0.049s ? blitiri.com.ar/go/chasquid/internal/trace [no test files] === RUN TestEmptyLoad - userdb_test.go:37: file: "/tmp/userdb_test1411367888" - userdb_test.go:37: file: "/tmp/userdb_test2963499615" ---- PASS: TestEmptyLoad (0.00s) + userdb_test.go:37: file: "/tmp/userdb_test3590744397" + userdb_test.go:37: file: "/tmp/userdb_test1209554330" +--- PASS: TestEmptyLoad (0.01s) === RUN TestWrite - userdb_test.go:37: file: "/tmp/userdb_test503708354" ---- PASS: TestWrite (0.95s) + userdb_test.go:37: file: "/tmp/userdb_test3040364987" +--- PASS: TestWrite (1.29s) === RUN TestNew ---- PASS: TestNew (0.13s) +--- PASS: TestNew (0.12s) === RUN TestInvalidUsername - userdb_test.go:37: file: "/tmp/userdb_test1415645278" + userdb_test.go:37: file: "/tmp/userdb_test1887704579" --- PASS: TestInvalidUsername (0.00s) === RUN TestPlainScheme - userdb_test.go:37: file: "/tmp/userdb_test1272644131" + userdb_test.go:37: file: "/tmp/userdb_test2048307090" --- PASS: TestPlainScheme (0.00s) === RUN TestReload - userdb_test.go:37: file: "/tmp/userdb_test2669351337" + userdb_test.go:37: file: "/tmp/userdb_test2489430293" --- PASS: TestReload (0.00s) === RUN TestRemoveUser - userdb_test.go:37: file: "/tmp/userdb_test3879233733" ---- PASS: TestRemoveUser (0.12s) + userdb_test.go:37: file: "/tmp/userdb_test508517629" +--- PASS: TestRemoveUser (0.18s) === RUN TestExists - userdb_test.go:37: file: "/tmp/userdb_test4103559805" ---- PASS: TestExists (0.12s) + userdb_test.go:37: file: "/tmp/userdb_test42762786" +--- PASS: TestExists (0.15s) PASS -ok blitiri.com.ar/go/chasquid/internal/userdb 1.339s +ok blitiri.com.ar/go/chasquid/internal/userdb 1.768s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1695,12 +1711,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/30232/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/30232/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/24386 and its subdirectories -I: Current time: Tue May 14 08:15:22 -12 2024 -I: pbuilder-time-stamp: 1715717722 +I: removing directory /srv/workspace/pbuilder/30232 and its subdirectories +I: Current time: Thu Apr 13 03:56:28 +14 2023 +I: pbuilder-time-stamp: 1681307788