1s autopkgtest [19:41:45]: starting date and time: 2026-01-22 19:41:45+0000 1s autopkgtest [19:41:45]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 1s autopkgtest [19:41:45]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gm51uyrh/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.14.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-arm64-3.secgroup --name adt-resolute-arm64-postfix-20260122-194144-juju-7f2275-prod-proposed-migration-environment-2-7a75c03e-1b55-48eb-8e1b-f7e1a7124d23 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-postfix-20260122-194144-juju-7f2275-prod-proposed-migration-environment-2-7a75c03e-1b55-48eb-8e1b-f7e1a7124d23 from image adt/ubuntu-resolute-arm64-server-20260122.img (UUID ed27afc7-5f46-4e8b-ad73-864280a0ddbb)... 64s autopkgtest [19:42:48]: testbed dpkg architecture: arm64 64s autopkgtest [19:42:48]: testbed apt version: 3.1.12 65s autopkgtest [19:42:49]: @@@@@@@@@@@@@@@@@@@@ test bed setup 65s autopkgtest [19:42:49]: testbed release detected to be: None 66s autopkgtest [19:42:50]: updating testbed package index (apt update) 67s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 67s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 67s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 67s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 67s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [19.2 kB] 67s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [815 kB] 67s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [80.9 kB] 67s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 67s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [103 kB] 67s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3316 B] 67s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [7876 B] 67s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [120 B] 67s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [639 kB] 68s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [16.2 kB] 68s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [4636 B] 68s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [460 B] 70s Fetched 1818 kB in 2s (1137 kB/s) 72s Reading package lists... 73s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 73s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 73s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 74s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 74s Reading package lists... 75s Reading package lists... 75s Building dependency tree... 75s Reading state information... 75s Calculating upgrade... 76s The following packages were automatically installed and are no longer required: 76s python3.13 python3.13-minimal 76s Use 'sudo apt autoremove' to remove them. 76s The following NEW packages will be installed: 76s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 76s The following packages will be upgraded: 76s libpython3-stdlib python3 python3-gdbm python3-minimal 76s 4 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 76s Need to get 6705 kB of archives. 76s After this operation, 26.0 MB of additional disk space will be used. 76s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-minimal arm64 3.14.2-1 [917 kB] 76s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14-minimal arm64 3.14.2-1 [2548 kB] 77s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-gdbm arm64 3.14.2-1 [11.1 kB] 77s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-minimal arm64 3.14.2-1 [28.2 kB] 77s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3 arm64 3.14.2-1 [22.9 kB] 77s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-stdlib arm64 3.14.2-1 [2351 kB] 77s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14 arm64 3.14.2-1 [816 kB] 77s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libpython3-stdlib arm64 3.14.2-1 [10.9 kB] 77s dpkg-preconfigure: unable to re-open stdin: No such file or directory 77s Fetched 6705 kB in 1s (6817 kB/s) 77s Selecting previously unselected package libpython3.14-minimal:arm64. 78s (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 ... 135223 files and directories currently installed.) 78s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_arm64.deb ... 78s Unpacking libpython3.14-minimal:arm64 (3.14.2-1) ... 78s Selecting previously unselected package python3.14-minimal. 78s Preparing to unpack .../python3.14-minimal_3.14.2-1_arm64.deb ... 78s Unpacking python3.14-minimal (3.14.2-1) ... 78s Preparing to unpack .../python3-gdbm_3.14.2-1_arm64.deb ... 78s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 78s Setting up libpython3.14-minimal:arm64 (3.14.2-1) ... 78s Setting up python3.14-minimal (3.14.2-1) ... 79s (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 ... 135565 files and directories currently installed.) 79s Preparing to unpack .../python3-minimal_3.14.2-1_arm64.deb ... 80s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 80s Setting up python3-minimal (3.14.2-1) ... 80s (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 ... 135565 files and directories currently installed.) 80s Preparing to unpack .../python3_3.14.2-1_arm64.deb ... 80s running python pre-rtupdate hooks for python3.14... 80s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 80s Selecting previously unselected package libpython3.14-stdlib:arm64. 80s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_arm64.deb ... 80s Unpacking libpython3.14-stdlib:arm64 (3.14.2-1) ... 80s Selecting previously unselected package python3.14. 80s Preparing to unpack .../python3.14_3.14.2-1_arm64.deb ... 80s Unpacking python3.14 (3.14.2-1) ... 81s Preparing to unpack .../libpython3-stdlib_3.14.2-1_arm64.deb ... 81s Unpacking libpython3-stdlib:arm64 (3.14.2-1) over (3.13.7-1) ... 81s Setting up libpython3.14-stdlib:arm64 (3.14.2-1) ... 81s Setting up python3.14 (3.14.2-1) ... 82s Setting up libpython3-stdlib:arm64 (3.14.2-1) ... 82s Setting up python3-gdbm (3.14.2-1) ... 82s Setting up python3 (3.14.2-1) ... 82s running python rtupdate hooks for python3.14... 82s running python post-rtupdate hooks for python3.14... 82s Processing triggers for man-db (2.13.1-1) ... 83s Processing triggers for systemd (259-1ubuntu2) ... 84s autopkgtest [19:43:08]: upgrading testbed (apt dist-upgrade and autopurge) 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s Calculating upgrade... 85s The following packages were automatically installed and are no longer required: 85s python3.13 python3.13-minimal 85s Use 'sudo apt autoremove' to remove them. 85s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Solving dependencies... 86s The following packages will be REMOVED: 86s python3.13* python3.13-minimal* 86s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 86s After this operation, 7766 kB disk space will be freed. 86s (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 ... 135995 files and directories currently installed.) 86s Removing python3.13 (3.13.11-1) ... 86s Removing python3.13-minimal (3.13.11-1) ... 88s Processing triggers for man-db (2.13.1-1) ... 88s Processing triggers for systemd (259-1ubuntu2) ... 88s (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 ... 135968 files and directories currently installed.) 88s Purging configuration files for python3.13-minimal (3.13.11-1) ... 91s autopkgtest [19:43:15]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 91s autopkgtest [19:43:15]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 98s Get:1 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (dsc) [3252 B] 98s Get:2 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (tar) [5040 kB] 98s Get:3 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (asc) [220 B] 98s Get:4 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (diff) [201 kB] 98s gpgv: Signature made Mon Dec 8 17:24:29 2025 UTC 98s gpgv: using RSA key 92978A6E195E4921825F7FF0F34F09744E9F5DD9 98s gpgv: Can't check signature: No public key 98s dpkg-source: warning: cannot verify inline signature for ./postfix_3.10.6-1ubuntu1.dsc: no acceptable signature found 98s autopkgtest [19:43:22]: testing package postfix version 3.10.6-1ubuntu1 99s autopkgtest [19:43:23]: build not needed 101s autopkgtest [19:43:25]: test postfix: preparing testbed 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s Solving dependencies... 102s The following NEW packages will be installed: 102s db-util db5.3-util procmail sasl2-bin ssl-cert 102s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 102s Need to get 336 kB of archives. 102s After this operation, 2266 kB of additional disk space will be used. 102s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 db5.3-util arm64 5.3.28+dfsg2-10ubuntu1 [72.6 kB] 102s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 db-util all 1:5.3.21ubuntu3 [2666 B] 102s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 sasl2-bin arm64 2.1.28+dfsg1-9ubuntu1 [95.0 kB] 102s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 procmail arm64 3.24-1ubuntu4 [147 kB] 102s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 ssl-cert all 1.1.3ubuntu2 [18.8 kB] 102s Preconfiguring packages ... 102s Fetched 336 kB in 0s (1371 kB/s) 102s Selecting previously unselected package db5.3-util. 102s (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 ... 135970 files and directories currently installed.) 102s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-10ubuntu1_arm64.deb ... 102s Unpacking db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 102s Selecting previously unselected package db-util. 102s Preparing to unpack .../db-util_1%3a5.3.21ubuntu3_all.deb ... 102s Unpacking db-util (1:5.3.21ubuntu3) ... 102s Selecting previously unselected package sasl2-bin. 103s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9ubuntu1_arm64.deb ... 103s Unpacking sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 103s Selecting previously unselected package procmail. 103s Preparing to unpack .../procmail_3.24-1ubuntu4_arm64.deb ... 103s Unpacking procmail (3.24-1ubuntu4) ... 103s Selecting previously unselected package ssl-cert. 103s Preparing to unpack .../ssl-cert_1.1.3ubuntu2_all.deb ... 103s Unpacking ssl-cert (1.1.3ubuntu2) ... 103s Setting up ssl-cert (1.1.3ubuntu2) ... 103s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 104s Setting up db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 104s Setting up procmail (3.24-1ubuntu4) ... 104s Setting up db-util (1:5.3.21ubuntu3) ... 104s Setting up sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 105s saslauthd.service is a disabled or a static unit, not starting it. 105s Processing triggers for man-db (2.13.1-1) ... 107s autopkgtest [19:43:31]: test postfix: [----------------------- 107s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Solving dependencies... 108s The following additional packages will be installed: 108s libnsl2 108s Suggested packages: 108s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 108s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 108s postfix-pgsql postfix-sqlite 108s The following NEW packages will be installed: 108s libnsl2 postfix 108s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 108s Need to get 1333 kB of archives. 108s After this operation, 6263 kB of additional disk space will be used. 108s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libnsl2 arm64 1.3.0-3build4 [41.7 kB] 108s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 postfix arm64 3.10.6-1ubuntu1 [1291 kB] 109s Preconfiguring packages ... 109s Fetched 1333 kB in 0s (4467 kB/s) 109s Selecting previously unselected package libnsl2:arm64. 109s (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 ... 136096 files and directories currently installed.) 109s Preparing to unpack .../libnsl2_1.3.0-3build4_arm64.deb ... 109s Unpacking libnsl2:arm64 (1.3.0-3build4) ... 109s Selecting previously unselected package postfix. 109s Preparing to unpack .../postfix_3.10.6-1ubuntu1_arm64.deb ... 109s Unpacking postfix (3.10.6-1ubuntu1) ... 109s Setting up libnsl2:arm64 (1.3.0-3build4) ... 109s Setting up postfix (3.10.6-1ubuntu1) ... 110s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 111s Creating /etc/postfix/dynamicmaps.cf 111s setting myhostname: localhost 111s setting alias maps 111s setting alias database 111s mailname is not a fully qualified domain name. Not changing /etc/mailname. 111s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 111s setting relayhost: 111s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 111s setting mailbox_command 111s setting mailbox_size_limit: 0 111s setting recipient_delimiter: + 113s setting inet_interfaces: all 115s setting inet_protocols: all 115s /etc/aliases does not exist, creating it. 115s WARNING: /etc/aliases exists, but does not have a root alias. 115s 115s Postfix (main.cf) is now set up with a default configuration. If you need to 115s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 115s Postfix configuration values, see postconf(1). 115s 115s After modifying main.cf, be sure to run 'systemctl reload postfix'. 115s 117s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 117s Processing triggers for ufw (0.36.2-9build1) ... 117s Processing triggers for man-db (2.13.1-1) ... 118s Processing triggers for libc-bin (2.42-2ubuntu4) ... 118s Processing triggers for postfix (3.10.6-1ubuntu1) ... 118s Restarting postfix 122s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 189s ........E...... 189s ====================================================================== 189s ERROR: test_11_security_CVE_2008_2936 (__main__.PostfixTest.test_11_security_CVE_2008_2936) 189s CVE-2008-2936 fixed 189s ---------------------------------------------------------------------- 189s Traceback (most recent call last): 189s File "/tmp/autopkgtest.cUMD1Z/build.38z/src/debian/tests/test-postfix.py", line 393, in test_11_security_CVE_2008_2936 189s os.link('/var/tmp/secret.link','/var/mail/%s' % (self.user.login)) 189s ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 189s PermissionError: [Errno 13] Permission denied: '/var/tmp/secret.link' -> '/var/mail/tfrnzvlo' 189s 189s ---------------------------------------------------------------------- 189s Ran 15 tests in 65.811s 189s 189s FAILED (errors=1) 189s ===== postfix.log contents: 189s Jan 22 19:43:46 localhost postfix/master[3159]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:43:46 localhost postfix/postfix-script[3256]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 189s Jan 22 19:43:49 localhost postfix/postfix-script[3719]: stopping the Postfix mail system 189s Jan 22 19:43:49 localhost postfix/master[3159]: terminating on signal 15 189s Jan 22 19:43:50 localhost postfix/master[3847]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: initializing the server-side TLS engine 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: initializing the server-side TLS engine 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: NOQUEUE: reject: VRFY from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; to= proto=ESMTP helo=<[127.0.0.1]> 189s Jan 22 19:43:52 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: B650141037: client=localhost[127.0.0.1] 189s Jan 22 19:43:52 localhost postfix/smtpd[3855]: B650141037: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 189s Jan 22 19:43:52 localhost postfix/cleanup[3906]: B650141037: message-id=<20260122194352.B650141037@localhost> 189s Jan 22 19:43:52 localhost postfix/qmgr[3849]: B650141037: from=, size=380, nrcpt=1 (queue active) 189s Jan 22 19:43:53 localhost postfix/local[3907]: B650141037: to=, orig_to=, relay=local, delay=1, delays=0.02/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:43:53 localhost postfix/qmgr[3849]: B650141037: removed 189s Jan 22 19:43:55 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 189s Jan 22 19:43:56 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:43:57 localhost postfix/smtpd[3852]: warning: SASL authentication failure: Password verification failed 189s Jan 22 19:43:57 localhost postfix/smtpd[3852]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 189s Jan 22 19:43:59 localhost postfix/smtpd[3852]: warning: SASL authentication failure: Password verification failed 189s Jan 22 19:43:59 localhost postfix/smtpd[3852]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tcprutur 189s Jan 22 19:44:00 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 189s Jan 22 19:44:00 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:00 localhost postfix/smtpd[3855]: 6102941037: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=txuemrxo 189s Jan 22 19:44:00 localhost postfix/smtpd[3855]: 6102941037: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> sasl_method=PLAIN sasl_username=txuemrxo 189s Jan 22 19:44:00 localhost postfix/cleanup[3906]: 6102941037: message-id=<20260122194400.6102941037@localhost> 189s Jan 22 19:44:00 localhost postfix/qmgr[3849]: 6102941037: from=, size=416, nrcpt=1 (queue active) 189s Jan 22 19:44:01 localhost postfix/local[3907]: 6102941037: to=, orig_to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:44:01 localhost postfix/qmgr[3849]: 6102941037: removed 189s Jan 22 19:44:03 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: setting up TLS connection from localhost[127.0.0.1] 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:before SSL initialization 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:before SSL initialization 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS read client hello 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS write server hello 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS write change cipher spec 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:TLSv1.3 write encrypted extensions 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS write certificate 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:TLSv1.3 write server certificate verify 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS write finished 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:TLSv1.3 early data 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:TLSv1.3 early data 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS read finished 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1769112842 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: SSL_accept:SSLv3/TLS write session ticket 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: Anonymous TLS connection established from localhost[127.0.0.1]: TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange x25519 server-signature RSA-PSS (2048 bits) server-digest SHA256 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: A853E41037: client=localhost[127.0.0.1] 189s Jan 22 19:44:03 localhost postfix/smtpd[3852]: A853E41037: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 189s Jan 22 19:44:03 localhost postfix/cleanup[3906]: A853E41037: message-id=<20260122194403.A853E41037@localhost> 189s Jan 22 19:44:03 localhost postfix/qmgr[3849]: A853E41037: from=, size=381, nrcpt=1 (queue active) 189s Jan 22 19:44:04 localhost postfix/local[3907]: A853E41037: to=, orig_to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:44:04 localhost postfix/qmgr[3849]: A853E41037: removed 189s Jan 22 19:44:06 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 189s Jan 22 19:44:06 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:07 localhost postfix/smtpd[3855]: 136CC4103D: client=localhost[127.0.0.1] 189s Jan 22 19:44:07 localhost postfix/smtpd[3855]: 136CC4103D: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 189s Jan 22 19:44:07 localhost postfix/cleanup[3906]: 136CC4103D: message-id=<20260122194407.136CC4103D@localhost> 189s Jan 22 19:44:07 localhost postfix/qmgr[3849]: 136CC4103D: from=, size=380, nrcpt=1 (queue active) 189s Jan 22 19:44:07 localhost postfix/cleanup[3906]: 156894103E: message-id=<20260122194407.136CC4103D@localhost> 189s Jan 22 19:44:07 localhost postfix/local[3907]: 136CC4103D: to=, orig_to=, relay=local, delay=0.01, delays=0.01/0/0/0, dsn=2.0.0, status=sent (forwarded as 156894103E) 189s Jan 22 19:44:07 localhost postfix/qmgr[3849]: 156894103E: from=, size=503, nrcpt=1 (queue active) 189s Jan 22 19:44:07 localhost postfix/qmgr[3849]: 136CC4103D: removed 189s Jan 22 19:44:08 localhost postfix/local[3907]: 156894103E: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:44:08 localhost postfix/qmgr[3849]: 156894103E: removed 189s Jan 22 19:44:10 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 189s Jan 22 19:44:10 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:10 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:10 localhost postfix/smtpd[3855]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:10 localhost postfix/smtpd[3855]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:10 localhost postfix/smtpd[3852]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:10 localhost postfix/smtpd[3852]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:14 localhost postfix/postfix-script[4139]: stopping the Postfix mail system 189s Jan 22 19:44:14 localhost postfix/master[3847]: terminating on signal 15 189s Jan 22 19:44:15 localhost postfix/master[4268]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: initializing the server-side TLS engine 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:17 localhost postfix/smtpd[4276]: initializing the server-side TLS engine 189s Jan 22 19:44:17 localhost postfix/smtpd[4276]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:17 localhost postfix/smtpd[4276]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:44:17 localhost postfix/smtpd[4276]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: warning: SASL authentication failure: incorrect digest response 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tweiimli 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: 8020741039: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tweiimli 189s Jan 22 19:44:17 localhost postfix/smtpd[4273]: 8020741039: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> sasl_method=CRAM-MD5 sasl_username=tweiimli 189s Jan 22 19:44:17 localhost postfix/cleanup[4278]: 8020741039: message-id=<20260122194417.8020741039@localhost> 189s Jan 22 19:44:17 localhost postfix/qmgr[4270]: 8020741039: from=, size=416, nrcpt=1 (queue active) 189s Jan 22 19:44:18 localhost postfix/local[4279]: 8020741039: to=, orig_to=, relay=local, delay=1, delays=0.02/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:44:18 localhost postfix/qmgr[4270]: 8020741039: removed 189s Jan 22 19:44:20 localhost postfix/smtpd[4273]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 189s Jan 22 19:44:20 localhost postfix/smtpd[4276]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:20 localhost postfix/smtpd[4276]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:24 localhost postfix/postfix-script[4322]: stopping the Postfix mail system 189s Jan 22 19:44:24 localhost postfix/master[4268]: terminating on signal 15 189s Jan 22 19:44:25 localhost postfix/master[4451]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:44:27 localhost postfix/smtpd[4456]: initializing the server-side TLS engine 189s Jan 22 19:44:27 localhost postfix/smtpd[4456]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: initializing the server-side TLS engine 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:44:27 localhost postfix/smtpd[4456]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:27 localhost postfix/smtpd[4459]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:31 localhost postfix/postfix-script[4500]: stopping the Postfix mail system 189s Jan 22 19:44:31 localhost postfix/master[4451]: terminating on signal 15 189s Jan 22 19:44:32 localhost postfix/master[4629]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:44:34 localhost postfix/smtpd[4635]: initializing the server-side TLS engine 189s Jan 22 19:44:34 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: initializing the server-side TLS engine 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:44:34 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:34 localhost postfix/smtpd[4638]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:38 localhost postfix/postfix-script[4679]: stopping the Postfix mail system 189s Jan 22 19:44:38 localhost postfix/master[4629]: terminating on signal 15 189s Jan 22 19:44:39 localhost postfix/master[4808]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: initializing the server-side TLS engine 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:41 localhost postfix/smtpd[4816]: initializing the server-side TLS engine 189s Jan 22 19:44:41 localhost postfix/smtpd[4816]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:41 localhost postfix/smtpd[4816]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:44:41 localhost postfix/smtpd[4816]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: warning: SASL authentication failure: Password verification failed 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: warning: SASL authentication failure: Password verification failed 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=thtpzcsv 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: 9FF8C41037: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=thtpzcsv 189s Jan 22 19:44:41 localhost postfix/smtpd[4813]: 9FF8C41037: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> sasl_method=PLAIN sasl_username=thtpzcsv 189s Jan 22 19:44:41 localhost postfix/cleanup[4818]: 9FF8C41037: message-id=<20260122194441.9FF8C41037@localhost> 189s Jan 22 19:44:41 localhost postfix/qmgr[4810]: 9FF8C41037: from=, size=416, nrcpt=1 (queue active) 189s Jan 22 19:44:42 localhost postfix/local[4819]: 9FF8C41037: to=, orig_to=, relay=local, delay=1, delays=0.02/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 189s Jan 22 19:44:42 localhost postfix/qmgr[4810]: 9FF8C41037: removed 189s Jan 22 19:44:44 localhost postfix/smtpd[4813]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 189s Jan 22 19:44:44 localhost postfix/smtpd[4816]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:44 localhost postfix/smtpd[4816]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s Jan 22 19:44:48 localhost postfix/postfix-script[4863]: stopping the Postfix mail system 189s Jan 22 19:44:48 localhost postfix/master[4808]: terminating on signal 15 189s Jan 22 19:44:49 localhost postfix/master[4992]: daemon started -- version 3.10.6, configuration /etc/postfix 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: initializing the server-side TLS engine 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:51 localhost postfix/smtpd[5002]: initializing the server-side TLS engine 189s Jan 22 19:44:51 localhost postfix/smtpd[5002]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:51 localhost postfix/smtpd[5002]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 189s Jan 22 19:44:51 localhost postfix/smtpd[5002]: disconnect from localhost[127.0.0.1] commands=0/0 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: warning: SASL authentication failure: Parse error 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: NOQUEUE: lost connection after AUTH from localhost[127.0.0.1] 189s Jan 22 19:44:51 localhost postfix/smtpd[4999]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 189s Jan 22 19:44:51 localhost postfix/smtpd[5002]: connect from localhost[127.0.0.1] 189s Jan 22 19:44:52 localhost postfix/smtpd[5002]: disconnect from localhost[127.0.0.1] quit=1 commands=1 189s autopkgtest [19:44:53]: test postfix: -----------------------] 189s postfix FAIL non-zero exit status 1 189s autopkgtest [19:44:53]: test postfix: - - - - - - - - - - results - - - - - - - - - - 190s autopkgtest [19:44:54]: @@@@@@@@@@@@@@@@@@@@ summary 190s postfix FAIL non-zero exit status 1