0s autopkgtest [19:28:04]: starting date and time: 2026-01-22 19:28:04+0000 0s autopkgtest [19:28:04]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [19:28:04]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.6za2whzx/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-ppc64el-7.secgroup --name adt-resolute-ppc64el-postfix-20260122-192804-juju-7f2275-prod-proposed-migration-environment-15-764f12b1-d051-4e71-b07f-a65e3db7a5a8 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 5s Creating nova instance adt-resolute-ppc64el-postfix-20260122-192804-juju-7f2275-prod-proposed-migration-environment-15-764f12b1-d051-4e71-b07f-a65e3db7a5a8 from image adt/ubuntu-resolute-ppc64el-server-20260122.img (UUID 987e5803-394f-43eb-a969-151b6b75a57a)... 54s autopkgtest [19:28:58]: testbed dpkg architecture: ppc64el 54s autopkgtest [19:28:58]: testbed apt version: 3.1.12 54s autopkgtest [19:28:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 54s autopkgtest [19:28:58]: testbed release detected to be: None 55s autopkgtest [19:28:59]: updating testbed package index (apt update) 55s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 55s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 55s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 55s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 55s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [19.2 kB] 55s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 56s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [815 kB] 56s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [80.9 kB] 56s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [103 kB] 56s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [618 kB] 56s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [4644 B] 56s Fetched 1768 kB in 1s (2428 kB/s) 57s Reading package lists... 57s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 57s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 57s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 57s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 58s Reading package lists... 58s Reading package lists... 58s Building dependency tree... 58s Reading state information... 59s Calculating upgrade... 59s The following packages were automatically installed and are no longer required: 59s python3.13 python3.13-minimal 59s Use 'sudo apt autoremove' to remove them. 59s The following NEW packages will be installed: 59s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 59s The following packages will be upgraded: 59s libpython3-stdlib python3 python3-gdbm python3-minimal 59s 4 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 59s Need to get 6966 kB of archives. 59s After this operation, 27.7 MB of additional disk space will be used. 59s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.2-1 [921 kB] 59s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.2-1 [2712 kB] 59s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-gdbm ppc64el 3.14.2-1 [11.1 kB] 59s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-minimal ppc64el 3.14.2-1 [28.2 kB] 59s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3 ppc64el 3.14.2-1 [22.9 kB] 59s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.2-1 [2444 kB] 59s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.2-1 [816 kB] 59s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpython3-stdlib ppc64el 3.14.2-1 [10.9 kB] 59s dpkg-preconfigure: unable to re-open stdin: No such file or directory 59s Fetched 6966 kB in 0s (24.0 MB/s) 59s Selecting previously unselected package libpython3.14-minimal:ppc64el. 60s (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 ... 120824 files and directories currently installed.) 60s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_ppc64el.deb ... 60s Unpacking libpython3.14-minimal:ppc64el (3.14.2-1) ... 60s Selecting previously unselected package python3.14-minimal. 60s Preparing to unpack .../python3.14-minimal_3.14.2-1_ppc64el.deb ... 60s Unpacking python3.14-minimal (3.14.2-1) ... 60s Preparing to unpack .../python3-gdbm_3.14.2-1_ppc64el.deb ... 60s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 60s Setting up libpython3.14-minimal:ppc64el (3.14.2-1) ... 60s Setting up python3.14-minimal (3.14.2-1) ... 62s (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 ... 121166 files and directories currently installed.) 62s Preparing to unpack .../python3-minimal_3.14.2-1_ppc64el.deb ... 62s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 62s Setting up python3-minimal (3.14.2-1) ... 62s (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 ... 121166 files and directories currently installed.) 62s Preparing to unpack .../python3_3.14.2-1_ppc64el.deb ... 62s running python pre-rtupdate hooks for python3.14... 62s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 62s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 62s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_ppc64el.deb ... 62s Unpacking libpython3.14-stdlib:ppc64el (3.14.2-1) ... 62s Selecting previously unselected package python3.14. 62s Preparing to unpack .../python3.14_3.14.2-1_ppc64el.deb ... 62s Unpacking python3.14 (3.14.2-1) ... 63s Preparing to unpack .../libpython3-stdlib_3.14.2-1_ppc64el.deb ... 63s Unpacking libpython3-stdlib:ppc64el (3.14.2-1) over (3.13.7-1) ... 63s Setting up libpython3.14-stdlib:ppc64el (3.14.2-1) ... 63s Setting up python3.14 (3.14.2-1) ... 64s Setting up libpython3-stdlib:ppc64el (3.14.2-1) ... 64s Setting up python3-gdbm (3.14.2-1) ... 64s Setting up python3 (3.14.2-1) ... 64s running python rtupdate hooks for python3.14... 64s running python post-rtupdate hooks for python3.14... 65s Processing triggers for man-db (2.13.1-1) ... 65s Processing triggers for systemd (259-1ubuntu2) ... 66s autopkgtest [19:29:10]: upgrading testbed (apt dist-upgrade and autopurge) 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 66s The following packages were automatically installed and are no longer required: 66s python3.13 python3.13-minimal 66s Use 'sudo apt autoremove' to remove them. 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 66s The following packages will be REMOVED: 66s python3.13* python3.13-minimal* 67s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 67s After this operation, 9077 kB disk space will be freed. 67s (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 ... 121596 files and directories currently installed.) 67s Removing python3.13 (3.13.11-1) ... 67s Removing python3.13-minimal (3.13.11-1) ... 68s Processing triggers for man-db (2.13.1-1) ... 68s Processing triggers for systemd (259-1ubuntu2) ... 68s (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 ... 121569 files and directories currently installed.) 68s Purging configuration files for python3.13-minimal (3.13.11-1) ... 71s autopkgtest [19:29:15]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:45:54 UTC 2026 71s autopkgtest [19:29:15]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 76s Get:1 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (dsc) [3252 B] 76s Get:2 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (tar) [5040 kB] 76s Get:3 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (asc) [220 B] 76s Get:4 http://ftpmaster.internal/ubuntu resolute/main postfix 3.10.6-1ubuntu1 (diff) [201 kB] 77s gpgv: Signature made Mon Dec 8 17:24:29 2025 UTC 77s gpgv: using RSA key 92978A6E195E4921825F7FF0F34F09744E9F5DD9 77s gpgv: Can't check signature: No public key 77s dpkg-source: warning: cannot verify inline signature for ./postfix_3.10.6-1ubuntu1.dsc: no acceptable signature found 77s autopkgtest [19:29:21]: testing package postfix version 3.10.6-1ubuntu1 78s autopkgtest [19:29:22]: build not needed 79s autopkgtest [19:29:23]: test postfix: preparing testbed 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Solving dependencies... 80s The following NEW packages will be installed: 80s db-util db5.3-util procmail sasl2-bin ssl-cert 80s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 80s Need to get 368 kB of archives. 80s After this operation, 2333 kB of additional disk space will be used. 80s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el db5.3-util ppc64el 5.3.28+dfsg2-10ubuntu1 [80.1 kB] 80s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el db-util all 1:5.3.21ubuntu3 [2666 B] 80s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el sasl2-bin ppc64el 2.1.28+dfsg1-9ubuntu1 [101 kB] 80s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el procmail ppc64el 3.24-1ubuntu4 [166 kB] 80s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el ssl-cert all 1.1.3ubuntu2 [18.8 kB] 80s Preconfiguring packages ... 80s Fetched 368 kB in 0s (958 kB/s) 80s Selecting previously unselected package db5.3-util. 81s (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 ... 121571 files and directories currently installed.) 81s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-10ubuntu1_ppc64el.deb ... 81s Unpacking db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 81s Selecting previously unselected package db-util. 81s Preparing to unpack .../db-util_1%3a5.3.21ubuntu3_all.deb ... 81s Unpacking db-util (1:5.3.21ubuntu3) ... 81s Selecting previously unselected package sasl2-bin. 81s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9ubuntu1_ppc64el.deb ... 81s Unpacking sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 81s Selecting previously unselected package procmail. 81s Preparing to unpack .../procmail_3.24-1ubuntu4_ppc64el.deb ... 81s Unpacking procmail (3.24-1ubuntu4) ... 81s Selecting previously unselected package ssl-cert. 81s Preparing to unpack .../ssl-cert_1.1.3ubuntu2_all.deb ... 81s Unpacking ssl-cert (1.1.3ubuntu2) ... 81s Setting up ssl-cert (1.1.3ubuntu2) ... 81s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 81s Setting up db5.3-util (5.3.28+dfsg2-10ubuntu1) ... 81s Setting up procmail (3.24-1ubuntu4) ... 81s Setting up db-util (1:5.3.21ubuntu3) ... 81s Setting up sasl2-bin (2.1.28+dfsg1-9ubuntu1) ... 82s saslauthd.service is a disabled or a static unit, not starting it. 82s Processing triggers for man-db (2.13.1-1) ... 85s autopkgtest [19:29:29]: test postfix: [----------------------- 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Solving dependencies... 85s The following additional packages will be installed: 85s libnsl2 85s Suggested packages: 85s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 85s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 85s postfix-pgsql postfix-sqlite 85s The following NEW packages will be installed: 85s libnsl2 postfix 85s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 85s Need to get 1469 kB of archives. 85s After this operation, 6917 kB of additional disk space will be used. 85s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libnsl2 ppc64el 1.3.0-3build4 [49.7 kB] 85s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el postfix ppc64el 3.10.6-1ubuntu1 [1419 kB] 86s Preconfiguring packages ... 87s Fetched 1469 kB in 0s (6827 kB/s) 87s Selecting previously unselected package libnsl2:ppc64el. 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 ... 121697 files and directories currently installed.) 87s Preparing to unpack .../libnsl2_1.3.0-3build4_ppc64el.deb ... 87s Unpacking libnsl2:ppc64el (1.3.0-3build4) ... 87s Selecting previously unselected package postfix. 87s Preparing to unpack .../postfix_3.10.6-1ubuntu1_ppc64el.deb ... 87s Unpacking postfix (3.10.6-1ubuntu1) ... 87s Setting up libnsl2:ppc64el (1.3.0-3build4) ... 87s Setting up postfix (3.10.6-1ubuntu1) ... 88s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 89s Creating /etc/postfix/dynamicmaps.cf 89s setting myhostname: localhost 89s setting alias maps 89s setting alias database 89s mailname is not a fully qualified domain name. Not changing /etc/mailname. 89s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 89s setting relayhost: 89s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 89s setting mailbox_command 89s setting mailbox_size_limit: 0 89s setting recipient_delimiter: + 91s setting inet_interfaces: all 93s setting inet_protocols: all 93s /etc/aliases does not exist, creating it. 93s WARNING: /etc/aliases exists, but does not have a root alias. 93s 93s Postfix (main.cf) is now set up with a default configuration. If you need to 93s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 93s Postfix configuration values, see postconf(1). 93s 93s After modifying main.cf, be sure to run 'systemctl reload postfix'. 93s 95s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 95s Processing triggers for ufw (0.36.2-9build1) ... 96s Processing triggers for man-db (2.13.1-1) ... 97s Processing triggers for libc-bin (2.42-2ubuntu4) ... 97s Processing triggers for postfix (3.10.6-1ubuntu1) ... 97s Restarting postfix 101s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 174s ........E...... 174s ====================================================================== 174s ERROR: test_11_security_CVE_2008_2936 (__main__.PostfixTest.test_11_security_CVE_2008_2936) 174s CVE-2008-2936 fixed 174s ---------------------------------------------------------------------- 174s Traceback (most recent call last): 174s File "/tmp/autopkgtest.ZBLBMf/build.8Gc/src/debian/tests/test-postfix.py", line 393, in test_11_security_CVE_2008_2936 174s os.link('/var/tmp/secret.link','/var/mail/%s' % (self.user.login)) 174s ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 174s PermissionError: [Errno 13] Permission denied: '/var/tmp/secret.link' -> '/var/mail/tlspwmly' 174s 174s ---------------------------------------------------------------------- 174s Ran 15 tests in 73.343s 174s 174s FAILED (errors=1) 174s ===== postfix.log contents: 175s Jan 22 19:29:44 localhost postfix/master[3260]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:29:45 localhost postfix/postfix-script[3357]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 175s Jan 22 19:29:47 localhost postfix/postfix-script[3820]: stopping the Postfix mail system 175s Jan 22 19:29:47 localhost postfix/master[3260]: terminating on signal 15 175s Jan 22 19:29:49 localhost postfix/master[3948]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:29:51 localhost postfix/smtpd[3953]: initializing the server-side TLS engine 175s Jan 22 19:29:51 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: initializing the server-side TLS engine 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:29:51 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 175s Jan 22 19:29:51 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:29:52 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:52 localhost postfix/smtpd[3953]: 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]> 175s Jan 22 19:29:52 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 175s Jan 22 19:29:52 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:52 localhost postfix/smtpd[3956]: BBE093E437: client=localhost[127.0.0.1] 175s Jan 22 19:29:52 localhost postfix/smtpd[3956]: BBE093E437: 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]> 175s Jan 22 19:29:52 localhost postfix/cleanup[4007]: BBE093E437: message-id=<20260122192952.BBE093E437@localhost> 175s Jan 22 19:29:52 localhost postfix/qmgr[3950]: BBE093E437: from=, size=380, nrcpt=1 (queue active) 175s Jan 22 19:29:53 localhost postfix/local[4008]: BBE093E437: 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") 175s Jan 22 19:29:53 localhost postfix/qmgr[3950]: BBE093E437: removed 175s Jan 22 19:29:55 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 175s Jan 22 19:29:56 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:29:57 localhost postfix/smtpd[3953]: warning: SASL authentication failure: Password verification failed 175s Jan 22 19:29:57 localhost postfix/smtpd[3953]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 175s Jan 22 19:29:59 localhost postfix/smtpd[3953]: warning: SASL authentication failure: Password verification failed 175s Jan 22 19:29:59 localhost postfix/smtpd[3953]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tlkmylpi 175s Jan 22 19:29:59 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 175s Jan 22 19:30:00 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:00 localhost postfix/smtpd[3956]: 5C9743E437: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tjjgmkof 175s Jan 22 19:30:00 localhost postfix/smtpd[3956]: 5C9743E437: 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=tjjgmkof 175s Jan 22 19:30:00 localhost postfix/cleanup[4007]: 5C9743E437: message-id=<20260122193000.5C9743E437@localhost> 175s Jan 22 19:30:00 localhost postfix/qmgr[3950]: 5C9743E437: from=, size=416, nrcpt=1 (queue active) 175s Jan 22 19:30:01 localhost postfix/local[4008]: 5C9743E437: to=, orig_to=, relay=local, delay=1, delays=0.02/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 175s Jan 22 19:30:01 localhost postfix/qmgr[3950]: 5C9743E437: removed 175s Jan 22 19:30:03 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: setting up TLS connection from localhost[127.0.0.1] 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:before SSL initialization 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:before SSL initialization 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS read client hello 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS write server hello 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS write change cipher spec 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:TLSv1.3 write encrypted extensions 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS write certificate 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:TLSv1.3 write server certificate verify 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS write finished 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:TLSv1.3 early data 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:TLSv1.3 early data 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS read finished 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1769112002 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: SSL_accept:SSLv3/TLS write session ticket 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: 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 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: A52DB3E437: client=localhost[127.0.0.1] 175s Jan 22 19:30:03 localhost postfix/smtpd[3953]: A52DB3E437: 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]> 175s Jan 22 19:30:03 localhost postfix/cleanup[4007]: A52DB3E437: message-id=<20260122193003.A52DB3E437@localhost> 175s Jan 22 19:30:03 localhost postfix/qmgr[3950]: A52DB3E437: from=, size=381, nrcpt=1 (queue active) 175s Jan 22 19:30:04 localhost postfix/local[4008]: A52DB3E437: 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") 175s Jan 22 19:30:04 localhost postfix/qmgr[3950]: A52DB3E437: removed 175s Jan 22 19:30:06 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 175s Jan 22 19:30:06 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:07 localhost postfix/smtpd[3956]: 0A6B63E43D: client=localhost[127.0.0.1] 175s Jan 22 19:30:07 localhost postfix/smtpd[3956]: 0A6B63E43D: 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]> 175s Jan 22 19:30:07 localhost postfix/cleanup[4007]: 0A6B63E43D: message-id=<20260122193007.0A6B63E43D@localhost> 175s Jan 22 19:30:07 localhost postfix/qmgr[3950]: 0A6B63E43D: from=, size=380, nrcpt=1 (queue active) 175s Jan 22 19:30:07 localhost postfix/cleanup[4007]: 0C12B3E43E: message-id=<20260122193007.0A6B63E43D@localhost> 175s Jan 22 19:30:07 localhost postfix/qmgr[3950]: 0C12B3E43E: from=, size=503, nrcpt=1 (queue active) 175s Jan 22 19:30:07 localhost postfix/local[4008]: 0A6B63E43D: to=, orig_to=, relay=local, delay=0.01, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (forwarded as 0C12B3E43E) 175s Jan 22 19:30:07 localhost postfix/qmgr[3950]: 0A6B63E43D: removed 175s Jan 22 19:30:08 localhost postfix/local[4008]: 0C12B3E43E: 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") 175s Jan 22 19:30:08 localhost postfix/qmgr[3950]: 0C12B3E43E: removed 175s Jan 22 19:30:10 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 175s Jan 22 19:30:10 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:10 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:10 localhost postfix/smtpd[3956]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:10 localhost postfix/smtpd[3956]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:10 localhost postfix/smtpd[3953]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:10 localhost postfix/smtpd[3953]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:15 localhost postfix/postfix-script[4240]: stopping the Postfix mail system 175s Jan 22 19:30:15 localhost postfix/master[3948]: terminating on signal 15 175s Jan 22 19:30:16 localhost postfix/master[4373]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: initializing the server-side TLS engine 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:18 localhost postfix/smtpd[4381]: initializing the server-side TLS engine 175s Jan 22 19:30:18 localhost postfix/smtpd[4381]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:18 localhost postfix/smtpd[4381]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:30:18 localhost postfix/smtpd[4381]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: warning: SASL authentication failure: incorrect digest response 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tehybffe 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: A7F6D3E439: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tehybffe 175s Jan 22 19:30:18 localhost postfix/smtpd[4378]: A7F6D3E439: 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=tehybffe 175s Jan 22 19:30:18 localhost postfix/cleanup[4383]: A7F6D3E439: message-id=<20260122193018.A7F6D3E439@localhost> 175s Jan 22 19:30:18 localhost postfix/qmgr[4375]: A7F6D3E439: from=, size=416, nrcpt=1 (queue active) 175s Jan 22 19:30:19 localhost postfix/local[4384]: A7F6D3E439: to=, orig_to=, relay=local, delay=1.1, delays=0.01/0/0/1.1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 175s Jan 22 19:30:19 localhost postfix/qmgr[4375]: A7F6D3E439: removed 175s Jan 22 19:30:21 localhost postfix/smtpd[4378]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 175s Jan 22 19:30:22 localhost postfix/smtpd[4381]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:22 localhost postfix/smtpd[4381]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:26 localhost postfix/postfix-script[4427]: stopping the Postfix mail system 175s Jan 22 19:30:26 localhost postfix/master[4373]: terminating on signal 15 175s Jan 22 19:30:27 localhost postfix/master[4556]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:30:29 localhost postfix/smtpd[4561]: initializing the server-side TLS engine 175s Jan 22 19:30:29 localhost postfix/smtpd[4561]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:29 localhost postfix/smtpd[4564]: initializing the server-side TLS engine 175s Jan 22 19:30:29 localhost postfix/smtpd[4564]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:29 localhost postfix/smtpd[4564]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:30:29 localhost postfix/smtpd[4561]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 175s Jan 22 19:30:29 localhost postfix/smtpd[4564]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:30:30 localhost postfix/smtpd[4561]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:30 localhost postfix/smtpd[4561]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:34 localhost postfix/postfix-script[4605]: stopping the Postfix mail system 175s Jan 22 19:30:34 localhost postfix/master[4556]: terminating on signal 15 175s Jan 22 19:30:35 localhost postfix/master[4734]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:30:37 localhost postfix/smtpd[4739]: initializing the server-side TLS engine 175s Jan 22 19:30:37 localhost postfix/smtpd[4739]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:37 localhost postfix/smtpd[4742]: initializing the server-side TLS engine 175s Jan 22 19:30:37 localhost postfix/smtpd[4742]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:37 localhost postfix/smtpd[4742]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:30:37 localhost postfix/smtpd[4742]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:30:37 localhost postfix/smtpd[4739]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 175s Jan 22 19:30:38 localhost postfix/smtpd[4742]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:38 localhost postfix/smtpd[4742]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:42 localhost postfix/postfix-script[4783]: stopping the Postfix mail system 175s Jan 22 19:30:42 localhost postfix/master[4734]: terminating on signal 15 175s Jan 22 19:30:43 localhost postfix/master[4912]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: initializing the server-side TLS engine 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:45 localhost postfix/smtpd[4920]: initializing the server-side TLS engine 175s Jan 22 19:30:45 localhost postfix/smtpd[4920]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:45 localhost postfix/smtpd[4920]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:30:45 localhost postfix/smtpd[4920]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: warning: SASL authentication failure: Password verification failed 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: warning: SASL authentication failure: Password verification failed 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tpcprxhc 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: CD3A93E437: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tpcprxhc 175s Jan 22 19:30:45 localhost postfix/smtpd[4917]: CD3A93E437: 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=tpcprxhc 175s Jan 22 19:30:45 localhost postfix/cleanup[4922]: CD3A93E437: message-id=<20260122193045.CD3A93E437@localhost> 175s Jan 22 19:30:45 localhost postfix/qmgr[4914]: CD3A93E437: from=, size=416, nrcpt=1 (queue active) 175s Jan 22 19:30:46 localhost postfix/local[4923]: CD3A93E437: to=, orig_to=, relay=local, delay=1.1, delays=0.03/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 175s Jan 22 19:30:46 localhost postfix/qmgr[4914]: CD3A93E437: removed 175s Jan 22 19:30:48 localhost postfix/smtpd[4917]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 175s Jan 22 19:30:49 localhost postfix/smtpd[4920]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:49 localhost postfix/smtpd[4920]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s Jan 22 19:30:53 localhost postfix/postfix-script[4966]: stopping the Postfix mail system 175s Jan 22 19:30:53 localhost postfix/master[4912]: terminating on signal 15 175s Jan 22 19:30:54 localhost postfix/master[5095]: daemon started -- version 3.10.6, configuration /etc/postfix 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: initializing the server-side TLS engine 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:56 localhost postfix/smtpd[5107]: initializing the server-side TLS engine 175s Jan 22 19:30:56 localhost postfix/smtpd[5107]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:56 localhost postfix/smtpd[5107]: NOQUEUE: lost connection after CONNECT from localhost[127.0.0.1] 175s Jan 22 19:30:56 localhost postfix/smtpd[5107]: disconnect from localhost[127.0.0.1] commands=0/0 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: warning: SASL authentication failure: Parse error 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: NOQUEUE: lost connection after AUTH from localhost[127.0.0.1] 175s Jan 22 19:30:56 localhost postfix/smtpd[5104]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 175s Jan 22 19:30:57 localhost postfix/smtpd[5107]: connect from localhost[127.0.0.1] 175s Jan 22 19:30:58 localhost postfix/smtpd[5107]: disconnect from localhost[127.0.0.1] quit=1 commands=1 175s autopkgtest [19:30:59]: test postfix: -----------------------] 175s autopkgtest [19:30:59]: test postfix: - - - - - - - - - - results - - - - - - - - - - 175s postfix FAIL non-zero exit status 1 176s autopkgtest [19:31:00]: @@@@@@@@@@@@@@@@@@@@ summary 176s postfix FAIL non-zero exit status 1