0s autopkgtest [12:08:07]: starting date and time: 2026-01-23 12:08:07+0000 0s autopkgtest [12:08:07]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:08:07]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ksz5uhio/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-s390x-11.secgroup --name adt-resolute-s390x-postfix-20260123-120807-juju-7f2275-prod-proposed-migration-environment-20-e1f746a0-c101-42c8-9b9a-e1b60e246fa7 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-postfix-20260123-120807-juju-7f2275-prod-proposed-migration-environment-20-e1f746a0-c101-42c8-9b9a-e1b60e246fa7 from image adt/ubuntu-resolute-s390x-server-20260123.img (UUID 5f2e306c-5dfc-4ef6-8be2-414f657c7562)... 84s autopkgtest [12:09:31]: testbed dpkg architecture: s390x 84s autopkgtest [12:09:31]: testbed apt version: 3.1.13 84s autopkgtest [12:09:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 85s autopkgtest [12:09:32]: testbed release detected to be: None 85s autopkgtest [12:09:32]: updating testbed package index (apt update) 86s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 86s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 86s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 86s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 86s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 86s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1016 kB] 86s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [92.8 kB] 86s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 86s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [115 kB] 87s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [826 kB] 87s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [9152 B] 87s Fetched 2210 kB in 1s (1709 kB/s) 90s Reading package lists... 92s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 92s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 92s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 92s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 96s Reading package lists... 96s Reading package lists... 97s Building dependency tree... 97s Reading state information... 98s Calculating upgrade... 99s The following packages were automatically installed and are no longer required: 99s python3.13 python3.13-minimal 99s Use 'sudo apt autoremove' to remove them. 99s The following NEW packages will be installed: 99s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 99s The following packages will be upgraded: 99s libpython3-stdlib python3 python3-gdbm python3-minimal 99s 4 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 99s Need to get 6689 kB of archives. 99s After this operation, 24.3 MB of additional disk space will be used. 99s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-minimal s390x 3.14.2-1 [917 kB] 99s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14-minimal s390x 3.14.2-1 [2510 kB] 99s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-minimal s390x 3.14.2-1 [28.2 kB] 99s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3 s390x 3.14.2-1 [22.9 kB] 99s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-stdlib s390x 3.14.2-1 [2373 kB] 99s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14 s390x 3.14.2-1 [816 kB] 99s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libpython3-stdlib s390x 3.14.2-1 [10.9 kB] 99s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-gdbm s390x 3.14.2-1 [11.1 kB] 100s dpkg-preconfigure: unable to re-open stdin: No such file or directory 100s Fetched 6689 kB in 0s (18.4 MB/s) 100s Selecting previously unselected package libpython3.14-minimal:s390x. 101s (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 ... 87685 files and directories currently installed.) 101s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_s390x.deb ... 101s Unpacking libpython3.14-minimal:s390x (3.14.2-1) ... 102s Selecting previously unselected package python3.14-minimal. 102s Preparing to unpack .../python3.14-minimal_3.14.2-1_s390x.deb ... 102s Unpacking python3.14-minimal (3.14.2-1) ... 102s Setting up libpython3.14-minimal:s390x (3.14.2-1) ... 102s Setting up python3.14-minimal (3.14.2-1) ... 107s (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 ... 88027 files and directories currently installed.) 107s Preparing to unpack .../python3-minimal_3.14.2-1_s390x.deb ... 107s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 108s Setting up python3-minimal (3.14.2-1) ... 108s (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 ... 88027 files and directories currently installed.) 108s Preparing to unpack .../python3_3.14.2-1_s390x.deb ... 109s running python pre-rtupdate hooks for python3.14... 109s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 109s Selecting previously unselected package libpython3.14-stdlib:s390x. 109s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_s390x.deb ... 109s Unpacking libpython3.14-stdlib:s390x (3.14.2-1) ... 110s Selecting previously unselected package python3.14. 110s Preparing to unpack .../python3.14_3.14.2-1_s390x.deb ... 110s Unpacking python3.14 (3.14.2-1) ... 110s Preparing to unpack .../libpython3-stdlib_3.14.2-1_s390x.deb ... 110s Unpacking libpython3-stdlib:s390x (3.14.2-1) over (3.13.9-3) ... 110s Preparing to unpack .../python3-gdbm_3.14.2-1_s390x.deb ... 110s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 110s Setting up libpython3.14-stdlib:s390x (3.14.2-1) ... 110s Setting up python3.14 (3.14.2-1) ... 114s Setting up libpython3-stdlib:s390x (3.14.2-1) ... 114s Setting up python3-gdbm (3.14.2-1) ... 114s Setting up python3 (3.14.2-1) ... 114s running python rtupdate hooks for python3.14... 114s running python post-rtupdate hooks for python3.14... 115s Processing triggers for man-db (2.13.1-1) ... 120s Processing triggers for systemd (259-1ubuntu2) ... 121s autopkgtest [12:10:08]: upgrading testbed (apt dist-upgrade and autopurge) 121s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Calculating upgrade... 122s The following packages were automatically installed and are no longer required: 122s python3.13 python3.13-minimal 122s Use 'sudo apt autoremove' to remove them. 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s Solving dependencies... 124s The following packages will be REMOVED: 124s python3.13* python3.13-minimal* 125s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 125s After this operation, 8012 kB disk space will be freed. 125s (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 ... 88457 files and directories currently installed.) 125s Removing python3.13 (3.13.11-1) ... 126s Removing python3.13-minimal (3.13.11-1) ... 128s Processing triggers for man-db (2.13.1-1) ... 129s Processing triggers for systemd (259-1ubuntu2) ... 129s (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 ... 88430 files and directories currently installed.) 129s Purging configuration files for python3.13-minimal (3.13.11-1) ... 133s autopkgtest [12:10:20]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP Mon Jan 12 15:39:23 UTC 2026 133s autopkgtest [12:10:20]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 143s Get:1 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (dsc) [3252 B] 143s Get:2 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (tar) [5040 kB] 143s Get:3 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (asc) [220 B] 143s Get:4 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (diff) [201 kB] 144s gpgv: Signature made Mon Dec 8 17:24:29 2025 UTC 144s gpgv: using RSA key 92978A6E195E4921825F7FF0F34F09744E9F5DD9 144s gpgv: Can't check signature: No public key 144s dpkg-source: warning: cannot verify inline signature for ./postfix_3.10.6-1ubuntu1.dsc: no acceptable signature found 145s autopkgtest [12:10:32]: testing package postfix version 3.10.6-1ubuntu1 146s autopkgtest [12:10:33]: build not needed 148s autopkgtest [12:10:35]: test postfix: preparing testbed 148s Reading package lists... 149s Building dependency tree... 149s Reading state information... 149s Solving dependencies... 150s The following NEW packages will be installed: 150s db-util db5.3-util procmail sasl2-bin ssl-cert 150s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 150s Need to get 350 kB of archives. 150s After this operation, 1062 kB of additional disk space will be used. 150s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x db5.3-util s390x 5.3.28+dfsg2-10ubuntu1 [75.0 kB] 150s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x db-util all 1:5.3.21ubuntu3 [2666 B] 150s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x sasl2-bin s390x 2.1.28+dfsg1-9ubuntu1 [97.2 kB] 150s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x procmail s390x 3.24-1ubuntu4 [157 kB] 150s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x ssl-cert all 1.1.3ubuntu2 [18.8 kB] 151s Preconfiguring packages ... 151s Fetched 350 kB in 0s (1111 kB/s) 151s Selecting previously unselected package db5.3-util. 151s (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 ... 88432 files and directories currently installed.) 151s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-10ubuntu1_s390x.deb ... 151s Unpacking db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 151s Selecting previously unselected package db-util. 151s Preparing to unpack .../db-util_1%3a5.3.21ubuntu3_all.deb ... 151s Unpacking db-util (1:5.3.21ubuntu3) ... 151s Selecting previously unselected package sasl2-bin. 151s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9ubuntu1_s390x.deb ... 151s Unpacking sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 151s Selecting previously unselected package procmail. 151s Preparing to unpack .../procmail_3.24-1ubuntu4_s390x.deb ... 151s Unpacking procmail (3.24-1ubuntu4) ... 152s Selecting previously unselected package ssl-cert. 152s Preparing to unpack .../ssl-cert_1.1.3ubuntu2_all.deb ... 152s Unpacking ssl-cert (1.1.3ubuntu2) ... 152s Setting up ssl-cert (1.1.3ubuntu2) ... 155s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 156s Setting up db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 156s Setting up procmail (3.24-1ubuntu4) ... 156s Setting up db-util (1:5.3.21ubuntu3) ... 156s Setting up sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 159s saslauthd.service is a disabled or a static unit, not starting it. 159s Processing triggers for man-db (2.13.1-1) ... 165s autopkgtest [12:10:52]: test postfix: [----------------------- 166s Reading package lists... 167s Building dependency tree... 167s Reading state information... 167s Solving dependencies... 167s The following additional packages will be installed: 167s libnsl2 167s Suggested packages: 167s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 167s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 167s postfix-pgsql postfix-sqlite 167s The following NEW packages will be installed: 167s libnsl2 postfix 167s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 1363 kB of archives. 167s After this operation, 4176 kB of additional disk space will be used. 167s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libnsl2 s390x 1.3.0-3build4 [44.0 kB] 168s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x postfix s390x 3.10.6-1ubuntu1 [1319 kB] 169s Preconfiguring packages ... 170s Fetched 1363 kB in 0s (3637 kB/s) 170s Selecting previously unselected package libnsl2:s390x. 170s (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 ... 88558 files and directories currently installed.) 170s Preparing to unpack .../libnsl2_1.3.0-3build4_s390x.deb ... 170s Unpacking libnsl2:s390x (1.3.0-3build4) ... 170s Selecting previously unselected package postfix. 170s Preparing to unpack .../postfix_3.10.6-1ubuntu1_s390x.deb ... 170s Unpacking postfix (3.10.6-1ubuntu1) ... 171s Setting up libnsl2:s390x (1.3.0-3build4) ... 171s Setting up postfix (3.10.6-1ubuntu1) ... 173s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 175s Creating /etc/postfix/dynamicmaps.cf 175s setting myhostname: localhost 175s setting alias maps 175s setting alias database 175s mailname is not a fully qualified domain name. Not changing /etc/mailname. 175s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 175s setting relayhost: 175s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 175s setting mailbox_command 175s setting mailbox_size_limit: 0 175s setting recipient_delimiter: + 178s setting inet_interfaces: all 180s setting inet_protocols: all 180s /etc/aliases does not exist, creating it. 180s WARNING: /etc/aliases exists, but does not have a root alias. 180s 180s Postfix (main.cf) is now set up with a default configuration. If you need to 180s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 180s Postfix configuration values, see postconf(1). 180s 180s After modifying main.cf, be sure to run 'systemctl reload postfix'. 180s 182s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 182s Processing triggers for ufw (0.36.2-9build1) ... 182s Processing triggers for man-db (2.13.1-1) ... 185s Processing triggers for libc-bin (2.42-2ubuntu4) ... 185s Processing triggers for postfix (3.10.6-1ubuntu1) ... 185s Restarting postfix 190s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 313s ........E...... 313s ====================================================================== 313s ERROR: test_11_security_CVE_2008_2936 (__main__.PostfixTest.test_11_security_CVE_2008_2936) 313s CVE-2008-2936 fixed 313s ---------------------------------------------------------------------- 313s Traceback (most recent call last): 313s File "/tmp/autopkgtest.76seE8/build.w5P/src/debian/tests/test-postfix.py", line 393, in test_11_security_CVE_2008_2936 313s os.link('/var/tmp/secret.link','/var/mail/%s' % (self.user.login)) 313s ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 313s PermissionError: [Errno 13] Permission denied: '/var/tmp/secret.link' -> '/var/mail/tzopwahg' 313s 313s ---------------------------------------------------------------------- 313s Ran 15 tests in 119.867s 313s 313s FAILED (errors=1) 313s ===== postfix.log contents: 313s Jan 23 12:13:30 localhost postfix/master[3148]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:13:31 localhost postfix/postfix-script[3245]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 313s Jan 23 12:13:46 localhost postfix/postfix-script[3708]: stopping the Postfix mail system 313s Jan 23 12:13:46 localhost postfix/master[3148]: terminating on signal 15 313s Jan 23 12:13:51 localhost postfix/master[3836]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:13:53 localhost postfix/smtpd[3841]: initializing the server-side TLS engine 313s Jan 23 12:13:53 localhost postfix/smtpd[3841]: connect from localhost[127.0.0.1] 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: initializing the server-side TLS engine 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:13:53 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:13:53 localhost postfix/smtpd[3841]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 313s Jan 23 12:13:56 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:13:56 localhost postfix/smtpd[3844]: 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]> 313s Jan 23 12:13:56 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 313s Jan 23 12:13:58 localhost postfix/smtpd[3841]: connect from localhost[127.0.0.1] 313s Jan 23 12:13:58 localhost postfix/smtpd[3841]: 3A60B81054: client=localhost[127.0.0.1] 313s Jan 23 12:13:58 localhost postfix/smtpd[3841]: 3A60B81054: 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]> 313s Jan 23 12:13:58 localhost postfix/cleanup[3892]: 3A60B81054: message-id=<20260123121358.3A60B81054@localhost> 313s Jan 23 12:13:58 localhost postfix/qmgr[3838]: 3A60B81054: from=, size=380, nrcpt=1 (queue active) 313s Jan 23 12:13:59 localhost postfix/local[3893]: 3A60B81054: to=, orig_to=, relay=local, delay=1.2, delays=0.01/0/0/1.1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:13:59 localhost postfix/qmgr[3838]: 3A60B81054: removed 313s Jan 23 12:14:01 localhost postfix/smtpd[3841]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 313s Jan 23 12:14:02 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:05 localhost postfix/smtpd[3844]: warning: SASL authentication failure: Password verification failed 313s Jan 23 12:14:05 localhost postfix/smtpd[3844]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 313s Jan 23 12:14:07 localhost postfix/smtpd[3844]: warning: SASL authentication failure: Password verification failed 313s Jan 23 12:14:07 localhost postfix/smtpd[3844]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=twdkdxph 313s Jan 23 12:14:08 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 313s Jan 23 12:14:09 localhost postfix/smtpd[3841]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:09 localhost postfix/smtpd[3841]: 880B581054: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tsmyplgs 313s Jan 23 12:14:09 localhost postfix/smtpd[3841]: 880B581054: 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=tsmyplgs 313s Jan 23 12:14:09 localhost postfix/cleanup[3892]: 880B581054: message-id=<20260123121409.880B581054@localhost> 313s Jan 23 12:14:09 localhost postfix/qmgr[3838]: 880B581054: from=, size=416, nrcpt=1 (queue active) 313s Jan 23 12:14:10 localhost postfix/local[3893]: 880B581054: to=, orig_to=, relay=local, delay=1.1, delays=0.03/0/0/1.1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:14:10 localhost postfix/qmgr[3838]: 880B581054: removed 313s Jan 23 12:14:12 localhost postfix/smtpd[3841]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: setting up TLS connection from localhost[127.0.0.1] 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:before SSL initialization 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:before SSL initialization 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS read client hello 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS write server hello 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS write change cipher spec 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:TLSv1.3 write encrypted extensions 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS write certificate 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:TLSv1.3 write server certificate verify 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS write finished 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:TLSv1.3 early data 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:TLSv1.3 early data 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS read finished 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1769172252 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: SSL_accept:SSLv3/TLS write session ticket 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: 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 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: 886D681054: client=localhost[127.0.0.1] 313s Jan 23 12:14:13 localhost postfix/smtpd[3844]: 886D681054: 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]> 313s Jan 23 12:14:13 localhost postfix/cleanup[3892]: 886D681054: message-id=<20260123121413.886D681054@localhost> 313s Jan 23 12:14:13 localhost postfix/qmgr[3838]: 886D681054: from=, size=381, nrcpt=1 (queue active) 313s Jan 23 12:14:14 localhost postfix/local[3893]: 886D681054: to=, orig_to=, relay=local, delay=1.2, delays=0.01/0/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:14:14 localhost postfix/qmgr[3838]: 886D681054: removed 313s Jan 23 12:14:16 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 313s Jan 23 12:14:17 localhost postfix/smtpd[3841]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:18 localhost postfix/smtpd[3841]: 2B0488105A: client=localhost[127.0.0.1] 313s Jan 23 12:14:18 localhost postfix/smtpd[3841]: 2B0488105A: 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]> 313s Jan 23 12:14:18 localhost postfix/cleanup[3892]: 2B0488105A: message-id=<20260123121418.2B0488105A@localhost> 313s Jan 23 12:14:18 localhost postfix/qmgr[3838]: 2B0488105A: from=, size=380, nrcpt=1 (queue active) 313s Jan 23 12:14:18 localhost postfix/cleanup[3892]: 33F888105B: message-id=<20260123121418.2B0488105A@localhost> 313s Jan 23 12:14:18 localhost postfix/qmgr[3838]: 33F888105B: from=, size=503, nrcpt=1 (queue active) 313s Jan 23 12:14:18 localhost postfix/local[3893]: 2B0488105A: to=, orig_to=, relay=local, delay=0.04, delays=0.04/0/0/0, dsn=2.0.0, status=sent (forwarded as 33F888105B) 313s Jan 23 12:14:18 localhost postfix/qmgr[3838]: 2B0488105A: removed 313s Jan 23 12:14:19 localhost postfix/local[3893]: 33F888105B: to=, orig_to=, relay=local, delay=1.2, delays=0/0/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:14:19 localhost postfix/qmgr[3838]: 33F888105B: removed 313s Jan 23 12:14:21 localhost postfix/smtpd[3841]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 313s Jan 23 12:14:22 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:22 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:14:22 localhost postfix/smtpd[3841]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:22 localhost postfix/smtpd[3841]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:14:24 localhost postfix/smtpd[3844]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:24 localhost postfix/smtpd[3844]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:14:29 localhost postfix/postfix-script[4123]: stopping the Postfix mail system 313s Jan 23 12:14:29 localhost postfix/master[3836]: terminating on signal 15 313s Jan 23 12:14:32 localhost postfix/master[4252]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:14:34 localhost postfix/smtpd[4261]: initializing the server-side TLS engine 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:35 localhost postfix/smtpd[4264]: initializing the server-side TLS engine 313s Jan 23 12:14:35 localhost postfix/smtpd[4264]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:35 localhost postfix/smtpd[4264]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:14:35 localhost postfix/smtpd[4264]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: warning: SASL authentication failure: incorrect digest response 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tvgdmfxz 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: 0939681056: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tvgdmfxz 313s Jan 23 12:14:35 localhost postfix/smtpd[4261]: 0939681056: 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=tvgdmfxz 313s Jan 23 12:14:35 localhost postfix/cleanup[4266]: 0939681056: message-id=<20260123121435.0939681056@localhost> 313s Jan 23 12:14:35 localhost postfix/qmgr[4254]: 0939681056: from=, size=416, nrcpt=1 (queue active) 313s Jan 23 12:14:36 localhost postfix/local[4267]: 0939681056: to=, orig_to=, relay=local, delay=1.1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:14:36 localhost postfix/qmgr[4254]: 0939681056: removed 313s Jan 23 12:14:38 localhost postfix/smtpd[4261]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 313s Jan 23 12:14:38 localhost postfix/smtpd[4264]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:38 localhost postfix/smtpd[4264]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:14:43 localhost postfix/postfix-script[4310]: stopping the Postfix mail system 313s Jan 23 12:14:44 localhost postfix/master[4252]: terminating on signal 15 313s Jan 23 12:14:47 localhost postfix/master[4439]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:14:49 localhost postfix/smtpd[4444]: initializing the server-side TLS engine 313s Jan 23 12:14:49 localhost postfix/smtpd[4444]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:49 localhost postfix/smtpd[4447]: initializing the server-side TLS engine 313s Jan 23 12:14:49 localhost postfix/smtpd[4447]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:49 localhost postfix/smtpd[4447]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:14:49 localhost postfix/smtpd[4447]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:14:49 localhost postfix/smtpd[4444]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 313s Jan 23 12:14:51 localhost postfix/smtpd[4447]: connect from localhost[127.0.0.1] 313s Jan 23 12:14:51 localhost postfix/smtpd[4447]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:14:57 localhost postfix/postfix-script[4489]: stopping the Postfix mail system 313s Jan 23 12:14:57 localhost postfix/master[4439]: terminating on signal 15 313s Jan 23 12:15:00 localhost postfix/master[4618]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:15:02 localhost postfix/smtpd[4623]: initializing the server-side TLS engine 313s Jan 23 12:15:02 localhost postfix/smtpd[4623]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:02 localhost postfix/smtpd[4626]: initializing the server-side TLS engine 313s Jan 23 12:15:02 localhost postfix/smtpd[4626]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:02 localhost postfix/smtpd[4626]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:15:02 localhost postfix/smtpd[4626]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:15:02 localhost postfix/smtpd[4623]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 313s Jan 23 12:15:03 localhost postfix/smtpd[4626]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:03 localhost postfix/smtpd[4626]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:15:08 localhost postfix/postfix-script[4667]: stopping the Postfix mail system 313s Jan 23 12:15:08 localhost postfix/master[4618]: terminating on signal 15 313s Jan 23 12:15:11 localhost postfix/master[4796]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:15:13 localhost postfix/smtpd[4801]: initializing the server-side TLS engine 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:14 localhost postfix/smtpd[4804]: initializing the server-side TLS engine 313s Jan 23 12:15:14 localhost postfix/smtpd[4804]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:14 localhost postfix/smtpd[4804]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:15:14 localhost postfix/smtpd[4804]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: warning: SASL authentication failure: Password verification failed 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: warning: SASL authentication failure: Password verification failed 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=txtxzqfu 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: 921AB81054: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=txtxzqfu 313s Jan 23 12:15:14 localhost postfix/smtpd[4801]: 921AB81054: 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=txtxzqfu 313s Jan 23 12:15:14 localhost postfix/cleanup[4806]: 921AB81054: message-id=<20260123121514.921AB81054@localhost> 313s Jan 23 12:15:14 localhost postfix/qmgr[4798]: 921AB81054: from=, size=416, nrcpt=1 (queue active) 313s Jan 23 12:15:15 localhost postfix/local[4807]: 921AB81054: to=, orig_to=, relay=local, delay=1.3, delays=0.23/0/0/1.1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 313s Jan 23 12:15:15 localhost postfix/qmgr[4798]: 921AB81054: removed 313s Jan 23 12:15:17 localhost postfix/smtpd[4801]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 313s Jan 23 12:15:18 localhost postfix/smtpd[4804]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:18 localhost postfix/smtpd[4804]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s Jan 23 12:15:23 localhost postfix/postfix-script[4850]: stopping the Postfix mail system 313s Jan 23 12:15:23 localhost postfix/master[4796]: terminating on signal 15 313s Jan 23 12:15:27 localhost postfix/master[4982]: daemon started -- version 3.10.6, configuration /etc/postfix 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: initializing the server-side TLS engine 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:29 localhost postfix/smtpd[4992]: initializing the server-side TLS engine 313s Jan 23 12:15:29 localhost postfix/smtpd[4992]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:29 localhost postfix/smtpd[4992]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 313s Jan 23 12:15:29 localhost postfix/smtpd[4992]: disconnect from localhost[127.0.0.1] commands=0/0 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: warning: SASL authentication failure: Parse error 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: NOQUEUE: lost connection after AUTH from localhost[127.0.0.1] 313s Jan 23 12:15:29 localhost postfix/smtpd[4989]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 313s Jan 23 12:15:29 localhost postfix/smtpd[4992]: connect from localhost[127.0.0.1] 313s Jan 23 12:15:34 localhost postfix/smtpd[4992]: disconnect from localhost[127.0.0.1] quit=1 commands=1 313s autopkgtest [12:13:20]: test postfix: -----------------------] 314s postfix FAIL non-zero exit status 1 314s autopkgtest [12:13:21]: test postfix: - - - - - - - - - - results - - - - - - - - - - 314s autopkgtest [12:13:21]: @@@@@@@@@@@@@@@@@@@@ summary 314s postfix FAIL non-zero exit status 1