0s autopkgtest [12:29:08]: starting date and time: 2026-01-24 12:29:08+0000 0s autopkgtest [12:29:08]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:29:08]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.a92x90lv/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-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-11.secgroup --name adt-resolute-amd64-postfix-20260124-122908-juju-7f2275-prod-proposed-migration-environment-15-b5280df6-74c6-42cc-bd6c-db6af090200e --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-amd64-postfix-20260124-122908-juju-7f2275-prod-proposed-migration-environment-15-b5280df6-74c6-42cc-bd6c-db6af090200e from image adt/ubuntu-resolute-amd64-server-20260124.img (UUID 46811f22-3700-43f4-953b-ee3c613d71bb)... 77s autopkgtest [12:30:25]: testbed dpkg architecture: amd64 77s autopkgtest [12:30:25]: testbed apt version: 3.1.13 77s autopkgtest [12:30:25]: @@@@@@@@@@@@@@@@@@@@ test bed setup 77s autopkgtest [12:30:25]: testbed release detected to be: None 78s autopkgtest [12:30:26]: updating testbed package index (apt update) 78s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 78s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 78s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 78s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [92.3 kB] 78s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 78s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [998 kB] 78s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [98.2 kB] 78s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [115 kB] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3712 B] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 79s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 79s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 79s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [385 kB] 79s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [905 kB] 79s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [24.9 kB] 79s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [12.4 kB] 79s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 79s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [824 B] 79s Fetched 2806 kB in 1s (2915 kB/s) 80s Reading package lists... 80s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 80s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 80s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 80s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 81s Reading package lists... 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s Calculating upgrade... 81s The following packages were automatically installed and are no longer required: 81s python3.13 python3.13-minimal 81s Use 'sudo apt autoremove' to remove them. 81s The following NEW packages will be installed: 81s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 81s The following packages will be upgraded: 81s libpython3-stdlib python3 python3-gdbm python3-minimal rust-coreutils 82s 5 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 82s Need to get 10.4 MB of archives. 82s After this operation, 25.2 MB of additional disk space will be used. 82s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 82s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 82s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 82s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 82s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 82s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 82s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 82s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 rust-coreutils amd64 0.5.0-0ubuntu1 [3628 kB] 82s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 82s dpkg-preconfigure: unable to re-open stdin: No such file or directory 82s Fetched 10.4 MB in 1s (16.8 MB/s) 82s Selecting previously unselected package libpython3.14-minimal:amd64. 82s (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 ... 124177 files and directories currently installed.) 82s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 82s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 82s Selecting previously unselected package python3.14-minimal. 82s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 82s Unpacking python3.14-minimal (3.14.2-1) ... 82s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 82s Setting up python3.14-minimal (3.14.2-1) ... 83s (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 ... 124519 files and directories currently installed.) 83s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 83s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 83s Setting up python3-minimal (3.14.2-1) ... 83s (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 ... 124519 files and directories currently installed.) 83s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 83s running python pre-rtupdate hooks for python3.14... 83s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 83s Selecting previously unselected package libpython3.14-stdlib:amd64. 83s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 83s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 84s Selecting previously unselected package python3.14. 84s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 84s Unpacking python3.14 (3.14.2-1) ... 84s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 84s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 84s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_amd64.deb ... 84s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 84s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 84s (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 ... 124980 files and directories currently installed.) 84s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 84s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 84s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 84s Setting up python3.14 (3.14.2-1) ... 84s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 84s Setting up python3-gdbm (3.14.2-1) ... 84s Setting up python3 (3.14.2-1) ... 84s running python rtupdate hooks for python3.14... 84s running python post-rtupdate hooks for python3.14... 85s Processing triggers for man-db (2.13.1-1) ... 85s Processing triggers for systemd (259-1ubuntu2) ... 85s autopkgtest [12:30:33]: upgrading testbed (apt dist-upgrade and autopurge) 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s 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. 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s 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, 7860 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 ... 124978 files and directories currently installed.) 86s Removing python3.13 (3.13.11-1) ... 86s Removing python3.13-minimal (3.13.11-1) ... 87s Processing triggers for man-db (2.13.1-1) ... 87s Processing triggers for systemd (259-1ubuntu2) ... 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 ... 124951 files and directories currently installed.) 87s Purging configuration files for python3.13-minimal (3.13.11-1) ... 89s autopkgtest [12:30:37]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 89s autopkgtest [12:30:37]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 93s Get:1 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (dsc) [3252 B] 93s Get:2 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (tar) [5040 kB] 93s Get:3 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (asc) [220 B] 93s Get:4 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (diff) [201 kB] 93s gpgv: Signature made Mon Dec 8 17:24:29 2025 UTC 93s gpgv: using RSA key 92978A6E195E4921825F7FF0F34F09744E9F5DD9 93s gpgv: Can't check signature: No public key 93s dpkg-source: warning: cannot verify inline signature for ./postfix_3.10.6-1ubuntu1.dsc: no acceptable signature found 94s autopkgtest [12:30:42]: testing package postfix version 3.10.6-1ubuntu1 94s autopkgtest [12:30:42]: build not needed 96s autopkgtest [12:30:44]: test postfix: preparing testbed 96s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s Solving dependencies... 96s The following NEW packages will be installed: 96s db-util db5.3-util procmail sasl2-bin ssl-cert 96s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 337 kB of archives. 96s After this operation, 1101 kB of additional disk space will be used. 96s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 db5.3-util amd64 5.3.28+dfsg2-10ubuntu1 [71.4 kB] 96s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 db-util all 1:5.3.21ubuntu3 [2666 B] 96s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 sasl2-bin amd64 2.1.28+dfsg1-9ubuntu1 [94.5 kB] 96s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 procmail amd64 3.24-1ubuntu4 [149 kB] 96s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 ssl-cert all 1.1.3ubuntu2 [18.8 kB] 97s Preconfiguring packages ... 97s Fetched 337 kB in 0s (1840 kB/s) 97s Selecting previously unselected package db5.3-util. 97s (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 ... 124953 files and directories currently installed.) 97s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-10ubuntu1_amd64.deb ... 97s Unpacking db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 97s Selecting previously unselected package db-util. 97s Preparing to unpack .../db-util_1%3a5.3.21ubuntu3_all.deb ... 97s Unpacking db-util (1:5.3.21ubuntu3) ... 97s Selecting previously unselected package sasl2-bin. 97s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9ubuntu1_amd64.deb ... 97s Unpacking sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 97s Selecting previously unselected package procmail. 97s Preparing to unpack .../procmail_3.24-1ubuntu4_amd64.deb ... 97s Unpacking procmail (3.24-1ubuntu4) ... 97s Selecting previously unselected package ssl-cert. 97s Preparing to unpack .../ssl-cert_1.1.3ubuntu2_all.deb ... 97s Unpacking ssl-cert (1.1.3ubuntu2) ... 97s Setting up ssl-cert (1.1.3ubuntu2) ... 97s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 97s Setting up db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 97s Setting up procmail (3.24-1ubuntu4) ... 97s Setting up db-util (1:5.3.21ubuntu3) ... 97s Setting up sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 98s saslauthd.service is a disabled or a static unit, not starting it. 98s Processing triggers for man-db (2.13.1-1) ... 99s autopkgtest [12:30:47]: test postfix: [----------------------- 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Solving dependencies... 99s The following additional packages will be installed: 99s libnsl2 99s Suggested packages: 99s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 99s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 99s postfix-pgsql postfix-sqlite 99s The following NEW packages will be installed: 99s libnsl2 postfix 99s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 99s Need to get 1347 kB of archives. 99s After this operation, 4289 kB of additional disk space will be used. 99s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libnsl2 amd64 1.3.0-3build4 [41.8 kB] 100s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 postfix amd64 3.10.6-1ubuntu1 [1306 kB] 100s Preconfiguring packages ... 100s Fetched 1347 kB in 0s (5308 kB/s) 100s Selecting previously unselected package libnsl2:amd64. 100s (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 ... 125079 files and directories currently installed.) 100s Preparing to unpack .../libnsl2_1.3.0-3build4_amd64.deb ... 100s Unpacking libnsl2:amd64 (1.3.0-3build4) ... 100s Selecting previously unselected package postfix. 100s Preparing to unpack .../postfix_3.10.6-1ubuntu1_amd64.deb ... 100s Unpacking postfix (3.10.6-1ubuntu1) ... 100s Setting up libnsl2:amd64 (1.3.0-3build4) ... 100s Setting up postfix (3.10.6-1ubuntu1) ... 100s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 101s Creating /etc/postfix/dynamicmaps.cf 101s setting myhostname: localhost 101s setting alias maps 101s setting alias database 101s mailname is not a fully qualified domain name. Not changing /etc/mailname. 101s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 101s setting relayhost: 101s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 101s setting mailbox_command 101s setting mailbox_size_limit: 0 101s setting recipient_delimiter: + 103s setting inet_interfaces: all 105s setting inet_protocols: all 105s /etc/aliases does not exist, creating it. 105s WARNING: /etc/aliases exists, but does not have a root alias. 105s 105s Postfix (main.cf) is now set up with a default configuration. If you need to 105s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 105s Postfix configuration values, see postconf(1). 105s 105s After modifying main.cf, be sure to run 'systemctl reload postfix'. 105s 107s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 107s Processing triggers for ufw (0.36.2-9build1) ... 107s Processing triggers for man-db (2.13.1-1) ... 108s Processing triggers for libc-bin (2.42-2ubuntu4) ... 108s Processing triggers for postfix (3.10.6-1ubuntu1) ... 108s Restarting postfix 110s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 171s ........E...... 171s ====================================================================== 171s ERROR: test_11_security_CVE_2008_2936 (__main__.PostfixTest.test_11_security_CVE_2008_2936) 171s CVE-2008-2936 fixed 171s ---------------------------------------------------------------------- 171s Traceback (most recent call last): 171s File "/tmp/autopkgtest.Q1nRkU/build.Lmd/src/debian/tests/test-postfix.py", line 393, in test_11_security_CVE_2008_2936 171s os.link('/var/tmp/secret.link','/var/mail/%s' % (self.user.login)) 171s ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 171s PermissionError: [Errno 13] Permission denied: '/var/tmp/secret.link' -> '/var/mail/tujglyvg' 171s 171s ---------------------------------------------------------------------- 171s Ran 15 tests in 60.170s 171s 171s FAILED (errors=1) 171s ===== postfix.log contents: 171s Jan 24 12:30:58 localhost postfix/master[3210]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:30:58 localhost postfix/postfix-script[3308]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 171s Jan 24 12:31:01 localhost postfix/postfix-script[3774]: stopping the Postfix mail system 171s Jan 24 12:31:01 localhost postfix/master[3210]: terminating on signal 15 171s Jan 24 12:31:01 localhost postfix/master[3902]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:03 localhost postfix/smtpd[3907]: initializing the server-side TLS engine 171s Jan 24 12:31:03 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: initializing the server-side TLS engine 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:03 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:03 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 171s Jan 24 12:31:04 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:04 localhost postfix/smtpd[3907]: 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]> 171s Jan 24 12:31:04 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 171s Jan 24 12:31:04 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:04 localhost postfix/smtpd[3910]: 192403E438: client=localhost[127.0.0.1] 171s Jan 24 12:31:04 localhost postfix/smtpd[3910]: 192403E438: 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]> 171s Jan 24 12:31:04 localhost postfix/cleanup[3958]: 192403E438: message-id=<20260124123104.192403E438@localhost> 171s Jan 24 12:31:04 localhost postfix/qmgr[3904]: 192403E438: from=, size=380, nrcpt=1 (queue active) 171s Jan 24 12:31:05 localhost postfix/local[3959]: 192403E438: 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") 171s Jan 24 12:31:05 localhost postfix/qmgr[3904]: 192403E438: removed 171s Jan 24 12:31:07 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 171s Jan 24 12:31:07 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:09 localhost postfix/smtpd[3907]: warning: SASL authentication failure: Password verification failed 171s Jan 24 12:31:09 localhost postfix/smtpd[3907]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 171s Jan 24 12:31:11 localhost postfix/smtpd[3907]: warning: SASL authentication failure: Password verification failed 171s Jan 24 12:31:11 localhost postfix/smtpd[3907]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=trydtrov 171s Jan 24 12:31:11 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 171s Jan 24 12:31:11 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:11 localhost postfix/smtpd[3910]: A194C3E438: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tbnnkbfr 171s Jan 24 12:31:11 localhost postfix/smtpd[3910]: A194C3E438: 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=tbnnkbfr 171s Jan 24 12:31:11 localhost postfix/cleanup[3958]: A194C3E438: message-id=<20260124123111.A194C3E438@localhost> 171s Jan 24 12:31:11 localhost postfix/qmgr[3904]: A194C3E438: from=, size=416, nrcpt=1 (queue active) 171s Jan 24 12:31:12 localhost postfix/local[3959]: A194C3E438: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 171s Jan 24 12:31:12 localhost postfix/qmgr[3904]: A194C3E438: removed 171s Jan 24 12:31:14 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: setting up TLS connection from localhost[127.0.0.1] 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:before SSL initialization 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:before SSL initialization 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS read client hello 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS write server hello 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS write change cipher spec 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:TLSv1.3 write encrypted extensions 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS write certificate 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:TLSv1.3 write server certificate verify 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS write finished 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:TLSv1.3 early data 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:TLSv1.3 early data 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS read finished 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1769259673 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: SSL_accept:SSLv3/TLS write session ticket 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: 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 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: B8EFE3E438: client=localhost[127.0.0.1] 171s Jan 24 12:31:14 localhost postfix/smtpd[3907]: B8EFE3E438: 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]> 171s Jan 24 12:31:14 localhost postfix/cleanup[3958]: B8EFE3E438: message-id=<20260124123114.B8EFE3E438@localhost> 171s Jan 24 12:31:14 localhost postfix/qmgr[3904]: B8EFE3E438: from=, size=381, nrcpt=1 (queue active) 171s Jan 24 12:31:15 localhost postfix/local[3959]: B8EFE3E438: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 171s Jan 24 12:31:15 localhost postfix/qmgr[3904]: B8EFE3E438: removed 171s Jan 24 12:31:17 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 171s Jan 24 12:31:17 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:17 localhost postfix/smtpd[3910]: DA9593E43E: client=localhost[127.0.0.1] 171s Jan 24 12:31:17 localhost postfix/smtpd[3910]: DA9593E43E: 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]> 171s Jan 24 12:31:17 localhost postfix/cleanup[3958]: DA9593E43E: message-id=<20260124123117.DA9593E43E@localhost> 171s Jan 24 12:31:17 localhost postfix/qmgr[3904]: DA9593E43E: from=, size=380, nrcpt=1 (queue active) 171s Jan 24 12:31:17 localhost postfix/cleanup[3958]: DB7163E43F: message-id=<20260124123117.DA9593E43E@localhost> 171s Jan 24 12:31:17 localhost postfix/local[3959]: DA9593E43E: to=, orig_to=, relay=local, delay=0.01, delays=0/0/0/0, dsn=2.0.0, status=sent (forwarded as DB7163E43F) 171s Jan 24 12:31:17 localhost postfix/qmgr[3904]: DB7163E43F: from=, size=503, nrcpt=1 (queue active) 171s Jan 24 12:31:17 localhost postfix/qmgr[3904]: DA9593E43E: removed 171s Jan 24 12:31:18 localhost postfix/local[3959]: DB7163E43F: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 171s Jan 24 12:31:18 localhost postfix/qmgr[3904]: DB7163E43F: removed 171s Jan 24 12:31:20 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 171s Jan 24 12:31:21 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:21 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:21 localhost postfix/smtpd[3910]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:21 localhost postfix/smtpd[3910]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:21 localhost postfix/smtpd[3907]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:21 localhost postfix/smtpd[3907]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:25 localhost postfix/postfix-script[4187]: stopping the Postfix mail system 171s Jan 24 12:31:25 localhost postfix/master[3902]: terminating on signal 15 171s Jan 24 12:31:26 localhost postfix/master[4319]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: initializing the server-side TLS engine 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:28 localhost postfix/smtpd[4327]: initializing the server-side TLS engine 171s Jan 24 12:31:28 localhost postfix/smtpd[4327]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:28 localhost postfix/smtpd[4327]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:28 localhost postfix/smtpd[4327]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: warning: SASL authentication failure: incorrect digest response 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tijksszk 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: D11543E43A: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tijksszk 171s Jan 24 12:31:28 localhost postfix/smtpd[4324]: D11543E43A: 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=tijksszk 171s Jan 24 12:31:28 localhost postfix/cleanup[4329]: D11543E43A: message-id=<20260124123128.D11543E43A@localhost> 171s Jan 24 12:31:28 localhost postfix/qmgr[4321]: D11543E43A: from=, size=416, nrcpt=1 (queue active) 171s Jan 24 12:31:29 localhost postfix/local[4330]: D11543E43A: 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") 171s Jan 24 12:31:29 localhost postfix/qmgr[4321]: D11543E43A: removed 171s Jan 24 12:31:31 localhost postfix/smtpd[4324]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 171s Jan 24 12:31:31 localhost postfix/smtpd[4327]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:31 localhost postfix/smtpd[4327]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:35 localhost postfix/postfix-script[4373]: stopping the Postfix mail system 171s Jan 24 12:31:35 localhost postfix/master[4319]: terminating on signal 15 171s Jan 24 12:31:35 localhost postfix/master[4502]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:37 localhost postfix/smtpd[4507]: initializing the server-side TLS engine 171s Jan 24 12:31:37 localhost postfix/smtpd[4507]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: initializing the server-side TLS engine 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:37 localhost postfix/smtpd[4507]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:37 localhost postfix/smtpd[4510]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:41 localhost postfix/postfix-script[4551]: stopping the Postfix mail system 171s Jan 24 12:31:41 localhost postfix/master[4502]: terminating on signal 15 171s Jan 24 12:31:41 localhost postfix/master[4680]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:43 localhost postfix/smtpd[4685]: initializing the server-side TLS engine 171s Jan 24 12:31:43 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: initializing the server-side TLS engine 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:43 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:43 localhost postfix/smtpd[4688]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:47 localhost postfix/postfix-script[4734]: stopping the Postfix mail system 171s Jan 24 12:31:47 localhost postfix/master[4680]: terminating on signal 15 171s Jan 24 12:31:47 localhost postfix/master[4863]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: initializing the server-side TLS engine 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:49 localhost postfix/smtpd[4871]: initializing the server-side TLS engine 171s Jan 24 12:31:49 localhost postfix/smtpd[4871]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:49 localhost postfix/smtpd[4871]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:49 localhost postfix/smtpd[4871]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: warning: SASL authentication failure: Password verification failed 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: warning: SASL authentication failure: Password verification failed 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tvgbzjcp 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: B64CD3E438: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tvgbzjcp 171s Jan 24 12:31:49 localhost postfix/smtpd[4868]: B64CD3E438: 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=tvgbzjcp 171s Jan 24 12:31:49 localhost postfix/cleanup[4874]: B64CD3E438: message-id=<20260124123149.B64CD3E438@localhost> 171s Jan 24 12:31:49 localhost postfix/qmgr[4865]: B64CD3E438: from=, size=416, nrcpt=1 (queue active) 171s Jan 24 12:31:50 localhost postfix/local[4875]: B64CD3E438: 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") 171s Jan 24 12:31:50 localhost postfix/qmgr[4865]: B64CD3E438: removed 171s Jan 24 12:31:52 localhost postfix/smtpd[4868]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 171s Jan 24 12:31:52 localhost postfix/smtpd[4871]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:52 localhost postfix/smtpd[4871]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s Jan 24 12:31:55 localhost postfix/postfix-script[4919]: stopping the Postfix mail system 171s Jan 24 12:31:55 localhost postfix/master[4863]: terminating on signal 15 171s Jan 24 12:31:56 localhost postfix/master[5048]: daemon started -- version 3.10.6, configuration /etc/postfix 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: initializing the server-side TLS engine 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:58 localhost postfix/smtpd[5058]: initializing the server-side TLS engine 171s Jan 24 12:31:58 localhost postfix/smtpd[5058]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:58 localhost postfix/smtpd[5058]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 171s Jan 24 12:31:58 localhost postfix/smtpd[5058]: disconnect from localhost[127.0.0.1] commands=0/0 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: warning: SASL authentication failure: Parse error 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: NOQUEUE: lost connection after AUTH from localhost[127.0.0.1] 171s Jan 24 12:31:58 localhost postfix/smtpd[5055]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 171s Jan 24 12:31:58 localhost postfix/smtpd[5058]: connect from localhost[127.0.0.1] 171s Jan 24 12:31:59 localhost postfix/smtpd[5058]: disconnect from localhost[127.0.0.1] quit=1 commands=1 171s autopkgtest [12:31:59]: test postfix: -----------------------] 172s autopkgtest [12:32:00]: test postfix: - - - - - - - - - - results - - - - - - - - - - 172s postfix FAIL non-zero exit status 1 172s autopkgtest [12:32:00]: @@@@@@@@@@@@@@@@@@@@ summary 172s postfix FAIL non-zero exit status 1