0s autopkgtest [06:48:02]: starting date and time: 2025-02-22 06:48:02+0000 0s autopkgtest [06:48:02]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [06:48:02]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.v0usniyu/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-17 --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=postgresql-17/17.4-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-23.secgroup --name adt-plucky-s390x-postfix-20250222-064802-juju-7f2275-prod-proposed-migration-environment-2-c2122e58-f40e-4cc6-bb06-63ec62a512ba --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 106s autopkgtest [06:49:48]: testbed dpkg architecture: s390x 106s autopkgtest [06:49:48]: testbed apt version: 2.9.30ubuntu1 106s autopkgtest [06:49:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 106s autopkgtest [06:49:48]: testbed release detected to be: None 107s autopkgtest [06:49:49]: updating testbed package index (apt update) 107s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 108s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 108s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 108s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 108s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 108s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 108s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 108s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 108s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 108s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 108s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [424 kB] 108s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2816 B] 108s Fetched 1264 kB in 1s (1439 kB/s) 109s Reading package lists... 109s Reading package lists... 109s Building dependency tree... 109s Reading state information... 110s Calculating upgrade... 110s Calculating upgrade... 110s The following packages were automatically installed and are no longer required: 110s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 110s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 110s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 110s linux-tools-6.11.0-8-generic 110s Use 'sudo apt autoremove' to remove them. 110s The following packages will be upgraded: 110s curl libcurl3t64-gnutls libcurl4t64 110s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 1041 kB of archives. 110s After this operation, 7168 B disk space will be freed. 110s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x curl s390x 8.12.1-2ubuntu1 [251 kB] 110s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl4t64 s390x 8.12.1-2ubuntu1 [397 kB] 110s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libcurl3t64-gnutls s390x 8.12.1-2ubuntu1 [393 kB] 111s Fetched 1041 kB in 1s (1660 kB/s) 111s (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 ... 81030 files and directories currently installed.) 111s Preparing to unpack .../curl_8.12.1-2ubuntu1_s390x.deb ... 111s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_s390x.deb ... 111s Unpacking libcurl4t64:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_s390x.deb ... 111s Unpacking libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 111s Setting up libcurl4t64:s390x (8.12.1-2ubuntu1) ... 111s Setting up libcurl3t64-gnutls:s390x (8.12.1-2ubuntu1) ... 111s Setting up curl (8.12.1-2ubuntu1) ... 111s Processing triggers for man-db (2.13.0-1) ... 111s Processing triggers for libc-bin (2.40-4ubuntu1) ... 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Solving dependencies... 112s The following packages will be REMOVED: 112s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 112s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 112s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 112s linux-tools-6.11.0-8-generic* 112s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 112s After this operation, 167 MB disk space will be freed. 112s (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 ... 81030 files and directories currently installed.) 112s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 112s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 112s Removing libpython3.12t64:s390x (3.12.9-1) ... 112s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 112s Removing libnsl2:s390x (1.3.0-3build3) ... 112s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 112s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 112s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 113s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 113s Processing triggers for libc-bin (2.40-4ubuntu1) ... 113s (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 ... 55930 files and directories currently installed.) 113s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 113s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 113s autopkgtest [06:49:55]: upgrading testbed (apt dist-upgrade and autopurge) 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 114s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 114s Starting 2 pkgProblemResolver with broken count: 0 114s Done 114s Entering ResolveByKeep 114s 114s Calculating upgrade... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Starting pkgProblemResolver with broken count: 0 115s Starting 2 pkgProblemResolver with broken count: 0 115s Done 115s Solving dependencies... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s autopkgtest [06:49:57]: rebooting testbed after setup commands that affected boot 134s autopkgtest [06:50:16]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 15:05:57 UTC 2025 136s autopkgtest [06:50:18]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 143s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (dsc) [3002 B] 143s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (tar) [4954 kB] 143s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (diff) [202 kB] 143s gpgv: Signature made Fri Jan 31 12:46:11 2025 UTC 143s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 143s gpgv: Can't check signature: No public key 143s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-10ubuntu1.dsc: no acceptable signature found 143s autopkgtest [06:50:25]: testing package postfix version 3.9.1-10ubuntu1 145s autopkgtest [06:50:27]: build not needed 150s autopkgtest [06:50:32]: test postfix: preparing testbed 150s Reading package lists... 150s Building dependency tree... 150s Reading state information... 150s Starting pkgProblemResolver with broken count: 0 150s Starting 2 pkgProblemResolver with broken count: 0 150s Done 151s The following NEW packages will be installed: 151s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 151s ssl-cert 151s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 151s Need to get 414 kB of archives. 151s After this operation, 1340 kB of additional disk space will be used. 151s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x db5.3-util s390x 5.3.28+dfsg2-9 [75.3 kB] 151s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x db-util all 1:5.3.21ubuntu2 [2412 B] 151s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x sasl2-bin s390x 2.1.28+dfsg1-8build1 [97.4 kB] 151s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x procmail s390x 3.24-1ubuntu3 [157 kB] 151s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-ptyprocess all 0.7.0-6 [14.8 kB] 151s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pexpect all 4.9-3 [48.1 kB] 151s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 151s Preconfiguring packages ... 151s Fetched 414 kB in 0s (879 kB/s) 151s Selecting previously unselected package db5.3-util. 151s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 55928 files and directories currently installed.) 151s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_s390x.deb ... 151s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 151s Selecting previously unselected package db-util. 151s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 151s Unpacking db-util (1:5.3.21ubuntu2) ... 151s Selecting previously unselected package sasl2-bin. 151s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8build1_s390x.deb ... 151s Unpacking sasl2-bin (2.1.28+dfsg1-8build1) ... 151s Selecting previously unselected package procmail. 151s Preparing to unpack .../3-procmail_3.24-1ubuntu3_s390x.deb ... 151s Unpacking procmail (3.24-1ubuntu3) ... 151s Selecting previously unselected package python3-ptyprocess. 151s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 151s Unpacking python3-ptyprocess (0.7.0-6) ... 151s Selecting previously unselected package python3-pexpect. 151s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 151s Unpacking python3-pexpect (4.9-3) ... 151s Selecting previously unselected package ssl-cert. 151s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 151s Unpacking ssl-cert (1.1.3ubuntu1) ... 151s Setting up ssl-cert (1.1.3ubuntu1) ... 152s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 152s Setting up db5.3-util (5.3.28+dfsg2-9) ... 152s Setting up procmail (3.24-1ubuntu3) ... 152s Setting up python3-ptyprocess (0.7.0-6) ... 152s Setting up db-util (1:5.3.21ubuntu2) ... 152s Setting up sasl2-bin (2.1.28+dfsg1-8build1) ... 153s saslauthd.service is a disabled or a static unit, not starting it. 153s Setting up python3-pexpect (4.9-3) ... 153s Processing triggers for man-db (2.13.0-1) ... 155s autopkgtest [06:50:37]: test postfix: [----------------------- 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 155s Solving dependencies... 155s The following additional packages will be installed: 155s libnsl2 155s Suggested packages: 155s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 155s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 155s postfix-pgsql postfix-sqlite 155s The following NEW packages will be installed: 155s libnsl2 postfix 155s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 155s Need to get 1349 kB of archives. 155s After this operation, 4132 kB of additional disk space will be used. 155s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libnsl2 s390x 1.3.0-3build3 [44.1 kB] 155s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x postfix s390x 3.9.1-10ubuntu1 [1305 kB] 156s Preconfiguring packages ... 156s Fetched 1349 kB in 1s (2246 kB/s) 156s Selecting previously unselected package libnsl2:s390x. 156s (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 ... 56094 files and directories currently installed.) 156s Preparing to unpack .../libnsl2_1.3.0-3build3_s390x.deb ... 156s Unpacking libnsl2:s390x (1.3.0-3build3) ... 156s Selecting previously unselected package postfix. 156s Preparing to unpack .../postfix_3.9.1-10ubuntu1_s390x.deb ... 156s Unpacking postfix (3.9.1-10ubuntu1) ... 156s Setting up libnsl2:s390x (1.3.0-3build3) ... 156s Setting up postfix (3.9.1-10ubuntu1) ... 156s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 157s info: Selecting UID from range 100 to 999 ... 157s 157s info: Selecting GID from range 100 to 999 ... 157s info: Adding system user `postfix' (UID 107) ... 157s info: Adding new group `postfix' (GID 113) ... 157s info: Adding new user `postfix' (UID 107) with group `postfix' ... 157s info: Not creating home directory `/var/spool/postfix'. 157s info: Selecting GID from range 100 to 999 ... 157s info: Adding group `postdrop' (GID 114) ... 157s Creating /etc/postfix/dynamicmaps.cf 157s setting myhostname: localhost 157s setting alias maps 157s setting alias database 157s mailname is not a fully qualified domain name. Not changing /etc/mailname. 157s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 157s setting relayhost: 157s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 157s setting mailbox_command 157s setting mailbox_size_limit: 0 157s setting recipient_delimiter: + 158s setting inet_interfaces: all 160s setting inet_protocols: all 160s /etc/aliases does not exist, creating it. 160s WARNING: /etc/aliases exists, but does not have a root alias. 160s 160s Postfix (main.cf) is now set up with a default configuration. If you need to 160s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 160s Postfix configuration values, see postconf(1). 160s 160s After modifying main.cf, be sure to run 'systemctl reload postfix'. 160s 161s Processing triggers for rsyslog (8.2412.0-2ubuntu1) ... 161s Processing triggers for ufw (0.36.2-9) ... 161s Processing triggers for man-db (2.13.0-1) ... 162s Processing triggers for libc-bin (2.40-4ubuntu1) ... 162s Processing triggers for postfix (3.9.1-10ubuntu1) ... 162s Restarting postfix 166s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 231s ............... 231s ---------------------------------------------------------------------- 231s Ran 15 tests in 65.610s 231s 231s OK 231s ===== postfix.log contents: 232s Feb 22 06:50:47 localhost postfix/master[2399]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:50:47 localhost postfix/postfix-script[2494]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 232s Feb 22 06:50:50 localhost postfix/postfix-script[2953]: stopping the Postfix mail system 232s Feb 22 06:50:50 localhost postfix/master[2399]: terminating on signal 15 232s Feb 22 06:50:51 localhost postfix/master[3079]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: initializing the server-side TLS engine 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: initializing the server-side TLS engine 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: 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]> 232s Feb 22 06:50:53 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: 80A8766B6: client=localhost[127.0.0.1] 232s Feb 22 06:50:53 localhost postfix/smtpd[3084]: 80A8766B6: 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]> 232s Feb 22 06:50:53 localhost postfix/cleanup[3129]: 80A8766B6: message-id=<20250222065053.80A8766B6@localhost> 232s Feb 22 06:50:53 localhost postfix/qmgr[3081]: 80A8766B6: from=, size=378, nrcpt=1 (queue active) 232s Feb 22 06:50:54 localhost postfix/local[3130]: 80A8766B6: 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") 232s Feb 22 06:50:54 localhost postfix/qmgr[3081]: 80A8766B6: removed 232s Feb 22 06:50:56 localhost postfix/smtpd[3084]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 232s Feb 22 06:50:56 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:50:59 localhost postfix/smtpd[3087]: warning: SASL authentication failure: Password verification failed 232s Feb 22 06:50:59 localhost postfix/smtpd[3087]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 232s Feb 22 06:51:00 localhost postfix/smtpd[3087]: warning: SASL authentication failure: Password verification failed 232s Feb 22 06:51:00 localhost postfix/smtpd[3087]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=taagbbpp 232s Feb 22 06:51:00 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 232s Feb 22 06:51:00 localhost postfix/smtpd[3084]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:00 localhost postfix/smtpd[3084]: EFBC466B6: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tdecyfza 232s Feb 22 06:51:00 localhost postfix/smtpd[3084]: EFBC466B6: 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]> 232s Feb 22 06:51:00 localhost postfix/cleanup[3129]: EFBC466B6: message-id=<20250222065100.EFBC466B6@localhost> 232s Feb 22 06:51:00 localhost postfix/qmgr[3081]: EFBC466B6: from=, size=414, nrcpt=1 (queue active) 232s Feb 22 06:51:01 localhost postfix/local[3130]: EFBC466B6: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Feb 22 06:51:01 localhost postfix/qmgr[3081]: EFBC466B6: removed 232s Feb 22 06:51:03 localhost postfix/smtpd[3084]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: setting up TLS connection from localhost[127.0.0.1] 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:before SSL initialization 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:before SSL initialization 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS read client hello 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS write server hello 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS write change cipher spec 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:TLSv1.3 write encrypted extensions 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS write certificate 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:TLSv1.3 write server certificate verify 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS write finished 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:TLSv1.3 early data 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:TLSv1.3 early data 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS read finished 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1740208863 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: SSL_accept:SSLv3/TLS write session ticket 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: 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 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: 152C566B6: client=localhost[127.0.0.1] 232s Feb 22 06:51:04 localhost postfix/smtpd[3087]: 152C566B6: 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]> 232s Feb 22 06:51:04 localhost postfix/cleanup[3129]: 152C566B6: message-id=<20250222065104.152C566B6@localhost> 232s Feb 22 06:51:04 localhost postfix/qmgr[3081]: 152C566B6: from=, size=379, nrcpt=1 (queue active) 232s Feb 22 06:51:05 localhost postfix/local[3130]: 152C566B6: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Feb 22 06:51:05 localhost postfix/qmgr[3081]: 152C566B6: removed 232s Feb 22 06:51:07 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 232s Feb 22 06:51:07 localhost postfix/smtpd[3084]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:07 localhost postfix/smtpd[3084]: 3664F66BC: client=localhost[127.0.0.1] 232s Feb 22 06:51:07 localhost postfix/smtpd[3084]: 3664F66BC: 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]> 232s Feb 22 06:51:07 localhost postfix/cleanup[3129]: 3664F66BC: message-id=<20250222065107.3664F66BC@localhost> 232s Feb 22 06:51:07 localhost postfix/qmgr[3081]: 3664F66BC: from=, size=378, nrcpt=1 (queue active) 232s Feb 22 06:51:07 localhost postfix/cleanup[3129]: 36D7866BE: message-id=<20250222065107.3664F66BC@localhost> 232s Feb 22 06:51:07 localhost postfix/local[3130]: 3664F66BC: to=, orig_to=, relay=local, delay=0, delays=0/0/0/0, dsn=2.0.0, status=sent (forwarded as 36D7866BE) 232s Feb 22 06:51:07 localhost postfix/qmgr[3081]: 36D7866BE: from=, size=500, nrcpt=1 (queue active) 232s Feb 22 06:51:07 localhost postfix/qmgr[3081]: 3664F66BC: removed 232s Feb 22 06:51:08 localhost postfix/local[3130]: 36D7866BE: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Feb 22 06:51:08 localhost postfix/qmgr[3081]: 36D7866BE: removed 232s Feb 22 06:51:10 localhost postfix/smtpd[3084]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 232s Feb 22 06:51:10 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:10 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:10 localhost postfix/smtpd[3084]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:10 localhost postfix/smtpd[3084]: 6933766B9: client=localhost[127.0.0.1] 232s Feb 22 06:51:10 localhost postfix/cleanup[3129]: 6933766B9: message-id=<20250222065110.6933766B9@localhost> 232s Feb 22 06:51:10 localhost postfix/qmgr[3081]: 6933766B9: from=, size=387, nrcpt=1 (queue active) 232s Feb 22 06:51:12 localhost postfix/smtpd[3084]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 232s Feb 22 06:51:12 localhost postfix/smtpd[3087]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:12 localhost postfix/smtpd[3087]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:16 localhost postfix/postfix-script[3324]: stopping the Postfix mail system 232s Feb 22 06:51:16 localhost postfix/master[3079]: terminating on signal 15 232s Feb 22 06:51:16 localhost postfix/master[3451]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: initializing the server-side TLS engine 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:18 localhost postfix/smtpd[3459]: initializing the server-side TLS engine 232s Feb 22 06:51:18 localhost postfix/smtpd[3459]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:18 localhost postfix/smtpd[3459]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:51:18 localhost postfix/smtpd[3459]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: warning: SASL authentication failure: incorrect digest response 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tboyejja 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: D8E3766BD: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tboyejja 232s Feb 22 06:51:18 localhost postfix/smtpd[3456]: D8E3766BD: 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]> 232s Feb 22 06:51:18 localhost postfix/cleanup[3461]: D8E3766BD: message-id=<20250222065118.D8E3766BD@localhost> 232s Feb 22 06:51:18 localhost postfix/qmgr[3453]: D8E3766BD: from=, size=414, nrcpt=1 (queue active) 232s Feb 22 06:51:19 localhost postfix/local[3462]: D8E3766BD: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Feb 22 06:51:19 localhost postfix/qmgr[3453]: D8E3766BD: removed 232s Feb 22 06:51:21 localhost postfix/smtpd[3456]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 232s Feb 22 06:51:21 localhost postfix/smtpd[3459]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:21 localhost postfix/smtpd[3459]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:25 localhost postfix/postfix-script[3502]: stopping the Postfix mail system 232s Feb 22 06:51:25 localhost postfix/master[3451]: terminating on signal 15 232s Feb 22 06:51:25 localhost postfix/master[3629]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:51:28 localhost postfix/smtpd[3634]: initializing the server-side TLS engine 232s Feb 22 06:51:28 localhost postfix/smtpd[3634]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:28 localhost postfix/smtpd[3637]: initializing the server-side TLS engine 232s Feb 22 06:51:28 localhost postfix/smtpd[3637]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:28 localhost postfix/smtpd[3637]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:51:28 localhost postfix/smtpd[3634]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Feb 22 06:51:28 localhost postfix/smtpd[3637]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:51:28 localhost postfix/smtpd[3634]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:28 localhost postfix/smtpd[3634]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:32 localhost postfix/postfix-script[3675]: stopping the Postfix mail system 232s Feb 22 06:51:32 localhost postfix/master[3629]: terminating on signal 15 232s Feb 22 06:51:33 localhost postfix/master[3805]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:51:36 localhost postfix/smtpd[3810]: initializing the server-side TLS engine 232s Feb 22 06:51:36 localhost postfix/smtpd[3810]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: initializing the server-side TLS engine 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:51:36 localhost postfix/smtpd[3810]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:36 localhost postfix/smtpd[3813]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:40 localhost postfix/postfix-script[3851]: stopping the Postfix mail system 232s Feb 22 06:51:40 localhost postfix/master[3805]: terminating on signal 15 232s Feb 22 06:51:41 localhost postfix/master[3978]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: initializing the server-side TLS engine 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:43 localhost postfix/smtpd[3986]: initializing the server-side TLS engine 232s Feb 22 06:51:43 localhost postfix/smtpd[3986]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:43 localhost postfix/smtpd[3986]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:51:43 localhost postfix/smtpd[3986]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: warning: SASL authentication failure: Password verification failed 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: warning: SASL authentication failure: Password verification failed 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tzbndufh 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: 2F63966B7: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tzbndufh 232s Feb 22 06:51:43 localhost postfix/smtpd[3983]: 2F63966B7: 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]> 232s Feb 22 06:51:43 localhost postfix/cleanup[3988]: 2F63966B7: message-id=<20250222065143.2F63966B7@localhost> 232s Feb 22 06:51:43 localhost postfix/qmgr[3980]: 2F63966B7: from=, size=414, nrcpt=1 (queue active) 232s Feb 22 06:51:44 localhost postfix/local[3989]: 2F63966B7: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Feb 22 06:51:44 localhost postfix/qmgr[3980]: 2F63966B7: removed 232s Feb 22 06:51:46 localhost postfix/smtpd[3983]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 232s Feb 22 06:51:46 localhost postfix/smtpd[3986]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:46 localhost postfix/smtpd[3986]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Feb 22 06:51:50 localhost postfix/postfix-script[4029]: stopping the Postfix mail system 232s Feb 22 06:51:50 localhost postfix/master[3978]: terminating on signal 15 232s Feb 22 06:51:50 localhost postfix/master[4156]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: initializing the server-side TLS engine 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:52 localhost postfix/smtpd[4166]: initializing the server-side TLS engine 232s Feb 22 06:51:52 localhost postfix/smtpd[4166]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:52 localhost postfix/smtpd[4166]: lost connection after CONNECT from localhost[127.0.0.1] 232s Feb 22 06:51:52 localhost postfix/smtpd[4166]: disconnect from localhost[127.0.0.1] commands=0/0 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: warning: SASL authentication failure: Parse error 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: lost connection after AUTH from localhost[127.0.0.1] 232s Feb 22 06:51:52 localhost postfix/smtpd[4163]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 232s Feb 22 06:51:53 localhost postfix/smtpd[4166]: connect from localhost[127.0.0.1] 232s Feb 22 06:51:53 localhost postfix/smtpd[4166]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s autopkgtest [06:51:54]: test postfix: -----------------------] 232s autopkgtest [06:51:54]: test postfix: - - - - - - - - - - results - - - - - - - - - - 232s postfix PASS 233s autopkgtest [06:51:55]: @@@@@@@@@@@@@@@@@@@@ summary 233s postfix PASS 237s nova [W] Using flock in prodstack6-s390x 237s Creating nova instance adt-plucky-s390x-postfix-20250222-064802-juju-7f2275-prod-proposed-migration-environment-2-c2122e58-f40e-4cc6-bb06-63ec62a512ba from image adt/ubuntu-plucky-s390x-server-20250222.img (UUID ae633cda-0dee-481c-9805-d0ef3f3b5166)... 237s nova [W] Timed out waiting for b644c26a-7dd1-4f98-9888-2e44680a7422 to get deleted.