0s autopkgtest [08:42:16]: starting date and time: 2026-01-23 08:42:16+0000 0s autopkgtest [08:42:16]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:42:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.rml7bizq/out --timeout-copy=6000 -a i386 --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-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-14.secgroup --name adt-resolute-i386-postfix-20260123-084215-juju-7f2275-prod-proposed-migration-environment-2-2d2d6c35-dffc-4995-a561-2e5fade01451 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 6s Creating nova instance adt-resolute-i386-postfix-20260123-084215-juju-7f2275-prod-proposed-migration-environment-2-2d2d6c35-dffc-4995-a561-2e5fade01451 from image adt/ubuntu-resolute-amd64-server-20260123.img (UUID 789dbc23-4f1b-4e7c-bcab-98e6eebf04f2)... 81s autopkgtest [08:43:37]: testbed dpkg architecture: amd64 82s autopkgtest [08:43:38]: testbed apt version: 3.1.13 82s autopkgtest [08:43:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 82s autopkgtest [08:43:38]: testbed release detected to be: None 83s autopkgtest [08:43:39]: updating testbed package index (apt update) 83s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 83s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 83s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 83s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 83s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [95.5 kB] 83s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1058 kB] 83s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 83s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 84s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [104 kB] 84s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [124 kB] 84s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3384 B] 84s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 84s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 84s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 84s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [402 kB] 84s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [926 kB] 84s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [22.3 kB] 84s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 84s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [11.6 kB] 84s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [564 B] 84s Fetched 2920 kB in 1s (2965 kB/s) 85s Reading package lists... 86s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 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 Reading package lists... 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Calculating upgrade... 87s The following packages were automatically installed and are no longer required: 87s python3.13 python3.13-minimal 87s Use 'sudo apt autoremove' to remove them. 87s The following NEW packages will be installed: 87s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 87s The following packages will be upgraded: 87s libpython3-stdlib python3 python3-gdbm python3-minimal 87s 4 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 87s Need to get 6769 kB of archives. 87s After this operation, 24.5 MB of additional disk space will be used. 87s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 87s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 87s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 87s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 87s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 87s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 87s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 87s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 87s dpkg-preconfigure: unable to re-open stdin: No such file or directory 87s Fetched 6769 kB in 0s (20.2 MB/s) 87s Selecting previously unselected package libpython3.14-minimal:amd64. 87s (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 ... 124176 files and directories currently installed.) 87s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 87s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 87s Selecting previously unselected package python3.14-minimal. 87s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 87s Unpacking python3.14-minimal (3.14.2-1) ... 87s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 87s Setting up python3.14-minimal (3.14.2-1) ... 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 ... 124518 files and directories currently installed.) 88s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 88s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 88s Setting up python3-minimal (3.14.2-1) ... 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 ... 124518 files and directories currently installed.) 88s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 88s running python pre-rtupdate hooks for python3.14... 88s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 89s Selecting previously unselected package libpython3.14-stdlib:amd64. 89s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 89s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 89s Selecting previously unselected package python3.14. 89s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 89s Unpacking python3.14 (3.14.2-1) ... 89s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 89s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 89s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 89s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 89s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 89s Setting up python3.14 (3.14.2-1) ... 89s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 89s Setting up python3-gdbm (3.14.2-1) ... 89s Setting up python3 (3.14.2-1) ... 89s running python rtupdate hooks for python3.14... 89s running python post-rtupdate hooks for python3.14... 89s Processing triggers for man-db (2.13.1-1) ... 90s Processing triggers for systemd (259-1ubuntu2) ... 90s autopkgtest [08:43:46]: upgrading testbed (apt dist-upgrade and autopurge) 90s Reading package lists... 90s Building dependency tree... 90s Reading state information... 90s Calculating upgrade... 90s The following packages were automatically installed and are no longer required: 90s python3.13 python3.13-minimal 90s Use 'sudo apt autoremove' to remove them. 90s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Solving dependencies... 91s The following packages will be REMOVED: 91s python3.13* python3.13-minimal* 91s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 91s After this operation, 7860 kB disk space will be freed. 91s (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 ... 124948 files and directories currently installed.) 91s Removing python3.13 (3.13.11-1) ... 91s Removing python3.13-minimal (3.13.11-1) ... 92s Processing triggers for man-db (2.13.1-1) ... 92s Processing triggers for systemd (259-1ubuntu2) ... 92s (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 ... 124921 files and directories currently installed.) 92s Purging configuration files for python3.13-minimal (3.13.11-1) ... 94s autopkgtest [08:43:50]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 94s autopkgtest [08:43:50]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 100s Get:1 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (dsc) [3252 B] 100s Get:2 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (tar) [5040 kB] 100s Get:3 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (asc) [220 B] 100s Get:4 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (diff) [201 kB] 100s gpgv: Signature made Mon Dec 8 17:24:29 2025 UTC 100s gpgv: using RSA key 92978A6E195E4921825F7FF0F34F09744E9F5DD9 100s gpgv: Can't check signature: No public key 100s dpkg-source: warning: cannot verify inline signature for ./postfix_3.10.6-1ubuntu1.dsc: no acceptable signature found 100s autopkgtest [08:43:56]: testing package postfix version 3.10.6-1ubuntu1 101s autopkgtest [08:43:57]: build not needed 102s autopkgtest [08:43:58]: test postfix: preparing testbed 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 103s Solving dependencies... 103s The following NEW packages will be installed: 103s db-util db5.3-util procmail sasl2-bin ssl-cert 103s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 103s Need to get 337 kB of archives. 103s After this operation, 1101 kB of additional disk space will be used. 103s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 db5.3-util amd64 5.3.28+dfsg2-10ubuntu1 [71.4 kB] 103s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 db-util all 1:5.3.21ubuntu3 [2666 B] 103s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 sasl2-bin amd64 2.1.28+dfsg1-9ubuntu1 [94.5 kB] 103s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 procmail amd64 3.24-1ubuntu4 [149 kB] 103s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 ssl-cert all 1.1.3ubuntu2 [18.8 kB] 103s Preconfiguring packages ... 103s Fetched 337 kB in 0s (2347 kB/s) 103s Selecting previously unselected package db5.3-util. 103s (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 ... 124923 files and directories currently installed.) 103s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-10ubuntu1_amd64.deb ... 103s Unpacking db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 103s Selecting previously unselected package db-util. 103s Preparing to unpack .../db-util_1%3a5.3.21ubuntu3_all.deb ... 103s Unpacking db-util (1:5.3.21ubuntu3) ... 103s Selecting previously unselected package sasl2-bin. 103s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9ubuntu1_amd64.deb ... 103s Unpacking sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 103s Selecting previously unselected package procmail. 103s Preparing to unpack .../procmail_3.24-1ubuntu4_amd64.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'. 103s Setting up db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 103s Setting up procmail (3.24-1ubuntu4) ... 103s Setting up db-util (1:5.3.21ubuntu3) ... 103s Setting up sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 104s saslauthd.service is a disabled or a static unit, not starting it. 104s Processing triggers for man-db (2.13.1-1) ... 105s autopkgtest [08:44:01]: test environment configured for cross building 105s autopkgtest [08:44:01]: test postfix: [----------------------- 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Solving dependencies... 106s The following additional packages will be installed: 106s libnsl2 106s Suggested packages: 106s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 106s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 106s postfix-pgsql postfix-sqlite 106s The following NEW packages will be installed: 106s libnsl2 postfix 107s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 107s Need to get 1347 kB of archives. 107s After this operation, 4289 kB of additional disk space will be used. 107s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libnsl2 amd64 1.3.0-3build4 [41.8 kB] 107s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 postfix amd64 3.10.6-1ubuntu1 [1306 kB] 107s Preconfiguring packages ... 107s Fetched 1347 kB in 1s (1626 kB/s) 107s Selecting previously unselected package libnsl2:amd64. 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 ... 125049 files and directories currently installed.) 107s Preparing to unpack .../libnsl2_1.3.0-3build4_amd64.deb ... 107s Unpacking libnsl2:amd64 (1.3.0-3build4) ... 107s Selecting previously unselected package postfix. 107s Preparing to unpack .../postfix_3.10.6-1ubuntu1_amd64.deb ... 107s Unpacking postfix (3.10.6-1ubuntu1) ... 107s Setting up libnsl2:amd64 (1.3.0-3build4) ... 107s Setting up postfix (3.10.6-1ubuntu1) ... 107s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 108s Creating /etc/postfix/dynamicmaps.cf 108s setting myhostname: localhost 108s setting alias maps 108s setting alias database 108s mailname is not a fully qualified domain name. Not changing /etc/mailname. 108s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 108s setting relayhost: 108s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 108s setting mailbox_command 108s setting mailbox_size_limit: 0 108s setting recipient_delimiter: + 109s setting inet_interfaces: all 111s setting inet_protocols: all 111s /etc/aliases does not exist, creating it. 111s WARNING: /etc/aliases exists, but does not have a root alias. 111s 111s Postfix (main.cf) is now set up with a default configuration. If you need to 111s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 111s Postfix configuration values, see postconf(1). 111s 111s After modifying main.cf, be sure to run 'systemctl reload postfix'. 111s 113s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 113s Processing triggers for ufw (0.36.2-9build1) ... 113s Processing triggers for man-db (2.13.1-1) ... 114s Processing triggers for libc-bin (2.42-2ubuntu4) ... 114s Processing triggers for postfix (3.10.6-1ubuntu1) ... 114s Restarting postfix 116s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 176s ........E...... 176s ====================================================================== 176s ERROR: test_11_security_CVE_2008_2936 (__main__.PostfixTest.test_11_security_CVE_2008_2936) 176s CVE-2008-2936 fixed 176s ---------------------------------------------------------------------- 176s Traceback (most recent call last): 176s File "/tmp/autopkgtest.Aeqswg/build.XMM/src/debian/tests/test-postfix.py", line 393, in test_11_security_CVE_2008_2936 176s os.link('/var/tmp/secret.link','/var/mail/%s' % (self.user.login)) 176s ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 176s PermissionError: [Errno 13] Permission denied: '/var/tmp/secret.link' -> '/var/mail/tevpzntm' 176s 176s ---------------------------------------------------------------------- 176s Ran 15 tests in 59.489s 176s 176s FAILED (errors=1) 176s ===== postfix.log contents: 176s Jan 23 08:44:12 localhost postfix/master[3214]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:44:12 localhost postfix/postfix-script[3312]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 176s Jan 23 08:44:15 localhost postfix/postfix-script[3776]: stopping the Postfix mail system 176s Jan 23 08:44:15 localhost postfix/master[3214]: terminating on signal 15 176s Jan 23 08:44:15 localhost postfix/master[3904]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: initializing the server-side TLS engine 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: initializing the server-side TLS engine 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: 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]> 176s Jan 23 08:44:18 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: 357473E437: client=localhost[127.0.0.1] 176s Jan 23 08:44:18 localhost postfix/smtpd[3909]: 357473E437: 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]> 176s Jan 23 08:44:18 localhost postfix/cleanup[3960]: 357473E437: message-id=<20260123084418.357473E437@localhost> 176s Jan 23 08:44:18 localhost postfix/qmgr[3906]: 357473E437: from=, size=380, nrcpt=1 (queue active) 176s Jan 23 08:44:19 localhost postfix/local[3961]: 357473E437: 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") 176s Jan 23 08:44:19 localhost postfix/qmgr[3906]: 357473E437: removed 176s Jan 23 08:44:21 localhost postfix/smtpd[3909]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 176s Jan 23 08:44:21 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:23 localhost postfix/smtpd[3912]: warning: SASL authentication failure: Password verification failed 176s Jan 23 08:44:23 localhost postfix/smtpd[3912]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 176s Jan 23 08:44:25 localhost postfix/smtpd[3912]: warning: SASL authentication failure: Password verification failed 176s Jan 23 08:44:25 localhost postfix/smtpd[3912]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=thxuemng 176s Jan 23 08:44:25 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 176s Jan 23 08:44:25 localhost postfix/smtpd[3909]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:25 localhost postfix/smtpd[3909]: BBC703E437: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tcdhohnd 176s Jan 23 08:44:25 localhost postfix/smtpd[3909]: BBC703E437: 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=tcdhohnd 176s Jan 23 08:44:25 localhost postfix/cleanup[3960]: BBC703E437: message-id=<20260123084425.BBC703E437@localhost> 176s Jan 23 08:44:25 localhost postfix/qmgr[3906]: BBC703E437: from=, size=416, nrcpt=1 (queue active) 176s Jan 23 08:44:26 localhost postfix/local[3961]: BBC703E437: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 176s Jan 23 08:44:26 localhost postfix/qmgr[3906]: BBC703E437: removed 176s Jan 23 08:44:28 localhost postfix/smtpd[3909]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: setting up TLS connection from localhost[127.0.0.1] 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:before SSL initialization 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:before SSL initialization 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS read client hello 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS write server hello 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS write change cipher spec 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:TLSv1.3 write encrypted extensions 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS write certificate 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:TLSv1.3 write server certificate verify 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS write finished 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:TLSv1.3 early data 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:TLSv1.3 early data 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS read finished 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1769159667 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: SSL_accept:SSLv3/TLS write session ticket 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: 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 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: D50403E437: client=localhost[127.0.0.1] 176s Jan 23 08:44:28 localhost postfix/smtpd[3912]: D50403E437: 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]> 176s Jan 23 08:44:28 localhost postfix/cleanup[3960]: D50403E437: message-id=<20260123084428.D50403E437@localhost> 176s Jan 23 08:44:28 localhost postfix/qmgr[3906]: D50403E437: from=, size=381, nrcpt=1 (queue active) 176s Jan 23 08:44:29 localhost postfix/local[3961]: D50403E437: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 176s Jan 23 08:44:29 localhost postfix/qmgr[3906]: D50403E437: removed 176s Jan 23 08:44:31 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 176s Jan 23 08:44:31 localhost postfix/smtpd[3909]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:32 localhost postfix/smtpd[3909]: 05F2D3E43D: client=localhost[127.0.0.1] 176s Jan 23 08:44:32 localhost postfix/smtpd[3909]: 05F2D3E43D: 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]> 176s Jan 23 08:44:32 localhost postfix/cleanup[3960]: 05F2D3E43D: message-id=<20260123084432.05F2D3E43D@localhost> 176s Jan 23 08:44:32 localhost postfix/qmgr[3906]: 05F2D3E43D: from=, size=380, nrcpt=1 (queue active) 176s Jan 23 08:44:32 localhost postfix/cleanup[3960]: 06D8E3E43E: message-id=<20260123084432.05F2D3E43D@localhost> 176s Jan 23 08:44:32 localhost postfix/qmgr[3906]: 06D8E3E43E: from=, size=503, nrcpt=1 (queue active) 176s Jan 23 08:44:32 localhost postfix/local[3961]: 05F2D3E43D: to=, orig_to=, relay=local, delay=0.01, delays=0/0/0/0, dsn=2.0.0, status=sent (forwarded as 06D8E3E43E) 176s Jan 23 08:44:32 localhost postfix/qmgr[3906]: 05F2D3E43D: removed 176s Jan 23 08:44:33 localhost postfix/local[3961]: 06D8E3E43E: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 176s Jan 23 08:44:33 localhost postfix/qmgr[3906]: 06D8E3E43E: removed 176s Jan 23 08:44:35 localhost postfix/smtpd[3909]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 176s Jan 23 08:44:35 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:35 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:44:35 localhost postfix/smtpd[3909]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:35 localhost postfix/smtpd[3909]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:44:35 localhost postfix/smtpd[3912]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:35 localhost postfix/smtpd[3912]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:44:39 localhost postfix/postfix-script[4189]: stopping the Postfix mail system 176s Jan 23 08:44:39 localhost postfix/master[3904]: terminating on signal 15 176s Jan 23 08:44:39 localhost postfix/master[4318]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: initializing the server-side TLS engine 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:41 localhost postfix/smtpd[4326]: initializing the server-side TLS engine 176s Jan 23 08:44:41 localhost postfix/smtpd[4326]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:41 localhost postfix/smtpd[4326]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:44:41 localhost postfix/smtpd[4326]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: warning: SASL authentication failure: incorrect digest response 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=teooevig 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: C38573E439: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=teooevig 176s Jan 23 08:44:41 localhost postfix/smtpd[4323]: C38573E439: 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=teooevig 176s Jan 23 08:44:41 localhost postfix/cleanup[4328]: C38573E439: message-id=<20260123084441.C38573E439@localhost> 176s Jan 23 08:44:41 localhost postfix/qmgr[4320]: C38573E439: from=, size=416, nrcpt=1 (queue active) 176s Jan 23 08:44:42 localhost postfix/local[4329]: C38573E439: 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") 176s Jan 23 08:44:42 localhost postfix/qmgr[4320]: C38573E439: removed 176s Jan 23 08:44:44 localhost postfix/smtpd[4323]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 176s Jan 23 08:44:44 localhost postfix/smtpd[4326]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:44 localhost postfix/smtpd[4326]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:44:48 localhost postfix/postfix-script[4372]: stopping the Postfix mail system 176s Jan 23 08:44:48 localhost postfix/master[4318]: terminating on signal 15 176s Jan 23 08:44:48 localhost postfix/master[4501]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:44:50 localhost postfix/smtpd[4506]: initializing the server-side TLS engine 176s Jan 23 08:44:50 localhost postfix/smtpd[4506]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:50 localhost postfix/smtpd[4509]: initializing the server-side TLS engine 176s Jan 23 08:44:50 localhost postfix/smtpd[4509]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:50 localhost postfix/smtpd[4509]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:44:50 localhost postfix/smtpd[4509]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:44:50 localhost postfix/smtpd[4506]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 176s Jan 23 08:44:50 localhost postfix/smtpd[4506]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:50 localhost postfix/smtpd[4506]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:44:54 localhost postfix/postfix-script[4550]: stopping the Postfix mail system 176s Jan 23 08:44:54 localhost postfix/master[4501]: terminating on signal 15 176s Jan 23 08:44:54 localhost postfix/master[4679]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:44:56 localhost postfix/smtpd[4685]: initializing the server-side TLS engine 176s Jan 23 08:44:56 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: initializing the server-side TLS engine 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:44:56 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: connect from localhost[127.0.0.1] 176s Jan 23 08:44:56 localhost postfix/smtpd[4688]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:45:00 localhost postfix/postfix-script[4729]: stopping the Postfix mail system 176s Jan 23 08:45:00 localhost postfix/master[4679]: terminating on signal 15 176s Jan 23 08:45:00 localhost postfix/master[4858]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: initializing the server-side TLS engine 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:02 localhost postfix/smtpd[4867]: initializing the server-side TLS engine 176s Jan 23 08:45:02 localhost postfix/smtpd[4867]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:02 localhost postfix/smtpd[4867]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: warning: SASL authentication failure: Password verification failed 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: warning: SASL authentication failure: Password verification failed 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=toreozij 176s Jan 23 08:45:02 localhost postfix/smtpd[4867]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: D1D033E437: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=toreozij 176s Jan 23 08:45:02 localhost postfix/smtpd[4864]: D1D033E437: 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=toreozij 176s Jan 23 08:45:02 localhost postfix/cleanup[4869]: D1D033E437: message-id=<20260123084502.D1D033E437@localhost> 176s Jan 23 08:45:02 localhost postfix/qmgr[4860]: D1D033E437: from=, size=416, nrcpt=1 (queue active) 176s Jan 23 08:45:03 localhost postfix/local[4870]: D1D033E437: 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") 176s Jan 23 08:45:03 localhost postfix/qmgr[4860]: D1D033E437: removed 176s Jan 23 08:45:05 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 176s Jan 23 08:45:05 localhost postfix/smtpd[4867]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:05 localhost postfix/smtpd[4867]: disconnect from localhost[127.0.0.1] quit=1 commands=1 176s Jan 23 08:45:09 localhost postfix/postfix-script[4918]: stopping the Postfix mail system 176s Jan 23 08:45:09 localhost postfix/master[4858]: terminating on signal 15 176s Jan 23 08:45:09 localhost postfix/master[5047]: daemon started -- version 3.10.6, configuration /etc/postfix 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: initializing the server-side TLS engine 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:11 localhost postfix/smtpd[5057]: initializing the server-side TLS engine 176s Jan 23 08:45:11 localhost postfix/smtpd[5057]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:11 localhost postfix/smtpd[5057]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 176s Jan 23 08:45:11 localhost postfix/smtpd[5057]: disconnect from localhost[127.0.0.1] commands=0/0 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: warning: SASL authentication failure: Parse error 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: NOQUEUE: lost connection after AUTH from localhost[127.0.0.1] 176s Jan 23 08:45:11 localhost postfix/smtpd[5054]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 176s Jan 23 08:45:11 localhost postfix/smtpd[5057]: connect from localhost[127.0.0.1] 176s Jan 23 08:45:12 localhost postfix/smtpd[5057]: disconnect from localhost[127.0.0.1] quit=1 commands=1 177s autopkgtest [08:45:13]: test postfix: -----------------------] 177s autopkgtest [08:45:13]: test postfix: - - - - - - - - - - results - - - - - - - - - - 177s postfix FAIL non-zero exit status 1 177s autopkgtest [08:45:13]: @@@@@@@@@@@@@@@@@@@@ summary 177s postfix FAIL non-zero exit status 1