0s autopkgtest [23:50:21]: starting date and time: 2025-01-13 23:50:21+0000 0s autopkgtest [23:50:21]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [23:50:21]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.yhjhqamc/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:openldap,src:libtool,src:sssd --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=openldap/2.6.9+dfsg-1~exp2ubuntu1 libtool/2.5.4-2 sssd/2.10.1-1ubuntu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-21.secgroup --name adt-plucky-s390x-postfix-20250113-235021-juju-7f2275-prod-proposed-migration-environment-2-358f33d0-af84-4dac-8c1b-763bbc16ecc4 --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/ 143s autopkgtest [23:52:44]: testbed dpkg architecture: s390x 143s autopkgtest [23:52:44]: testbed apt version: 2.9.18 144s autopkgtest [23:52:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 144s autopkgtest [23:52:45]: testbed release detected to be: None 145s autopkgtest [23:52:46]: updating testbed package index (apt update) 145s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 145s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 145s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 145s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 145s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [134 kB] 145s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [818 kB] 145s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [10.3 kB] 145s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 145s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [242 kB] 146s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 146s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [879 kB] 146s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4072 B] 146s Fetched 2172 kB in 1s (2326 kB/s) 146s Reading package lists... 147s + lsb_release --codename --short 147s + RELEASE=plucky 147s + cat 147s + [ plucky != trusty ] 147s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 147s Reading package lists... 147s Building dependency tree... 147s Reading state information... 147s Calculating upgrade... 147s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 147s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 147s + /usr/lib/apt/apt-helper analyze-pattern ?true 147s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 148s Reading package lists... 148s Building dependency tree... 148s Reading state information... 148s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 148s + grep -q trusty /etc/lsb-release 148s + [ ! -d /usr/share/doc/unattended-upgrades ] 148s + [ ! -d /usr/share/doc/lxd ] 148s + [ ! -d /usr/share/doc/lxd-client ] 148s + [ ! -d /usr/share/doc/snapd ] 148s + type iptables 148s + cat 148s + chmod 755 /etc/rc.local 148s + . /etc/rc.local 148s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 148s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 148s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 148s + uname -m 148s + [ s390x = ppc64le ] 148s + [ -d /run/systemd/system ] 148s + systemd-detect-virt --quiet --vm 148s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 148s + cat 148s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 148s + echo COMPRESS=lz4 148s autopkgtest [23:52:49]: upgrading testbed (apt dist-upgrade and autopurge) 148s Reading package lists... 148s Building dependency tree... 148s Reading state information... 148s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 148s Starting 2 pkgProblemResolver with broken count: 0 148s Done 149s Entering ResolveByKeep 149s 149s The following packages will be upgraded: 149s libldap-common libldap2 149s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 149s Need to get 237 kB of archives. 149s After this operation, 9216 B of additional disk space will be used. 149s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libldap-common all 2.6.9+dfsg-1~exp2ubuntu1 [33.2 kB] 149s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libldap2 s390x 2.6.9+dfsg-1~exp2ubuntu1 [204 kB] 150s Fetched 237 kB in 0s (568 kB/s) 150s (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 ... 55642 files and directories currently installed.) 150s Preparing to unpack .../libldap-common_2.6.9+dfsg-1~exp2ubuntu1_all.deb ... 150s Unpacking libldap-common (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 150s Preparing to unpack .../libldap2_2.6.9+dfsg-1~exp2ubuntu1_s390x.deb ... 150s Unpacking libldap2:s390x (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 150s Setting up libldap-common (2.6.9+dfsg-1~exp2ubuntu1) ... 150s Installing new version of config file /etc/ldap/ldap.conf ... 150s Setting up libldap2:s390x (2.6.9+dfsg-1~exp2ubuntu1) ... 150s Processing triggers for man-db (2.13.0-1) ... 150s Processing triggers for libc-bin (2.40-4ubuntu1) ... 150s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s Starting pkgProblemResolver with broken count: 0 151s Starting 2 pkgProblemResolver with broken count: 0 151s Done 151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 153s autopkgtest [23:52:54]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 153s autopkgtest [23:52:54]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 159s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 159s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 159s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 159s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 159s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 159s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 159s gpgv: Can't check signature: No public key 159s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 159s autopkgtest [23:53:00]: testing package postfix version 3.9.1-9 160s autopkgtest [23:53:01]: build not needed 162s autopkgtest [23:53:03]: test postfix: preparing testbed 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 162s Starting pkgProblemResolver with broken count: 0 162s Starting 2 pkgProblemResolver with broken count: 0 162s Done 162s The following NEW packages will be installed: 162s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 162s ssl-cert 163s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 163s Need to get 414 kB of archives. 163s After this operation, 1336 kB of additional disk space will be used. 163s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x db5.3-util s390x 5.3.28+dfsg2-9 [75.3 kB] 163s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x db-util all 1:5.3.21ubuntu2 [2412 B] 163s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x sasl2-bin s390x 2.1.28+dfsg1-8 [97.2 kB] 163s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x procmail s390x 3.24-1ubuntu3 [157 kB] 163s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-ptyprocess all 0.7.0-6 [14.8 kB] 163s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pexpect all 4.9-3 [48.1 kB] 163s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 163s Preconfiguring packages ... 163s Fetched 414 kB in 0s (870 kB/s) 163s Selecting previously unselected package db5.3-util. 163s (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 ... 55641 files and directories currently installed.) 163s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_s390x.deb ... 163s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 163s Selecting previously unselected package db-util. 163s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 163s Unpacking db-util (1:5.3.21ubuntu2) ... 163s Selecting previously unselected package sasl2-bin. 163s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_s390x.deb ... 163s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 163s Selecting previously unselected package procmail. 163s Preparing to unpack .../3-procmail_3.24-1ubuntu3_s390x.deb ... 163s Unpacking procmail (3.24-1ubuntu3) ... 163s Selecting previously unselected package python3-ptyprocess. 163s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 163s Unpacking python3-ptyprocess (0.7.0-6) ... 163s Selecting previously unselected package python3-pexpect. 163s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 163s Unpacking python3-pexpect (4.9-3) ... 163s Selecting previously unselected package ssl-cert. 163s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 163s Unpacking ssl-cert (1.1.3ubuntu1) ... 163s Setting up ssl-cert (1.1.3ubuntu1) ... 164s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 164s Setting up db5.3-util (5.3.28+dfsg2-9) ... 164s Setting up procmail (3.24-1ubuntu3) ... 164s Setting up python3-ptyprocess (0.7.0-6) ... 164s Setting up db-util (1:5.3.21ubuntu2) ... 164s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 164s saslauthd.service is a disabled or a static unit, not starting it. 164s Setting up python3-pexpect (4.9-3) ... 165s Processing triggers for man-db (2.13.0-1) ... 166s autopkgtest [23:53:07]: test postfix: [----------------------- 166s Reading package lists... 167s Building dependency tree... 167s Reading state information... 167s The following additional packages will be installed: 167s libicu74 167s Suggested packages: 167s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 167s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 167s postfix-pgsql postfix-sqlite 167s The following NEW packages will be installed: 167s libicu74 postfix 167s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 12.2 MB of archives. 167s After this operation, 41.1 MB of additional disk space will be used. 167s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libicu74 s390x 74.2-1ubuntu4 [10.9 MB] 168s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x postfix s390x 3.9.1-9 [1304 kB] 168s Preconfiguring packages ... 168s Fetched 12.2 MB in 1s (13.3 MB/s) 168s Selecting previously unselected package libicu74:s390x. 168s (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 ... 55807 files and directories currently installed.) 168s Preparing to unpack .../libicu74_74.2-1ubuntu4_s390x.deb ... 168s Unpacking libicu74:s390x (74.2-1ubuntu4) ... 168s Selecting previously unselected package postfix. 168s Preparing to unpack .../postfix_3.9.1-9_s390x.deb ... 168s Unpacking postfix (3.9.1-9) ... 168s Setting up libicu74:s390x (74.2-1ubuntu4) ... 168s Setting up postfix (3.9.1-9) ... 168s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 169s info: Selecting UID from range 100 to 999 ... 169s 169s info: Selecting GID from range 100 to 999 ... 169s info: Adding system user `postfix' (UID 107) ... 169s info: Adding new group `postfix' (GID 113) ... 169s info: Adding new user `postfix' (UID 107) with group `postfix' ... 169s info: Not creating home directory `/var/spool/postfix'. 169s info: Selecting GID from range 100 to 999 ... 169s info: Adding group `postdrop' (GID 114) ... 169s Creating /etc/postfix/dynamicmaps.cf 169s setting myhostname: localhost 169s setting alias maps 169s setting alias database 169s mailname is not a fully qualified domain name. Not changing /etc/mailname. 169s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 169s setting relayhost: 169s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 169s setting mailbox_command 169s setting mailbox_size_limit: 0 169s setting recipient_delimiter: + 171s setting inet_interfaces: all 173s setting inet_protocols: all 173s /etc/aliases does not exist, creating it. 173s WARNING: /etc/aliases exists, but does not have a root alias. 173s 173s Postfix (main.cf) is now set up with a default configuration. If you need to 173s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 173s Postfix configuration values, see postconf(1). 173s 173s After modifying main.cf, be sure to run 'systemctl reload postfix'. 173s 175s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 175s Processing triggers for ufw (0.36.2-8) ... 175s Processing triggers for man-db (2.13.0-1) ... 176s Processing triggers for libc-bin (2.40-4ubuntu1) ... 176s Processing triggers for postfix (3.9.1-9) ... 176s Restarting postfix 180s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 245s ............... 245s ---------------------------------------------------------------------- 245s Ran 15 tests in 64.638s 245s 245s OK 245s ===== postfix.log contents: 245s Jan 13 23:53:19 localhost postfix/postfix-script[2827]: waiting for the Postfix mail system to terminate 245s Jan 13 23:53:20 localhost postfix/master[3247]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:53:21 localhost postfix/postfix-script[3495]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 245s Jan 13 23:53:23 localhost postfix/postfix-script[4251]: stopping the Postfix mail system 245s Jan 13 23:53:23 localhost postfix/master[3247]: terminating on signal 15 245s Jan 13 23:53:24 localhost postfix/master[4677]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: initializing the server-side TLS engine 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: initializing the server-side TLS engine 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: 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]> 245s Jan 13 23:53:26 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: 724FE3F64B: client=localhost[127.0.0.1] 245s Jan 13 23:53:26 localhost postfix/smtpd[4685]: 724FE3F64B: 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]> 245s Jan 13 23:53:26 localhost postfix/cleanup[4723]: 724FE3F64B: message-id=<20250113235326.724FE3F64B@localhost> 245s Jan 13 23:53:26 localhost postfix/qmgr[4679]: 724FE3F64B: from=, size=380, nrcpt=1 (queue active) 245s Jan 13 23:53:27 localhost postfix/local[4724]: 724FE3F64B: 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") 245s Jan 13 23:53:27 localhost postfix/qmgr[4679]: 724FE3F64B: removed 245s Jan 13 23:53:29 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 245s Jan 13 23:53:29 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:31 localhost postfix/smtpd[4682]: warning: SASL authentication failure: Password verification failed 245s Jan 13 23:53:31 localhost postfix/smtpd[4682]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 245s Jan 13 23:53:32 localhost postfix/smtpd[4682]: warning: SASL authentication failure: Password verification failed 245s Jan 13 23:53:32 localhost postfix/smtpd[4682]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=ttpmnhec 245s Jan 13 23:53:32 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 245s Jan 13 23:53:33 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:33 localhost postfix/smtpd[4685]: 0BCBA3F64B: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=trxogjvl 245s Jan 13 23:53:33 localhost postfix/smtpd[4685]: 0BCBA3F64B: 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]> 245s Jan 13 23:53:33 localhost postfix/cleanup[4723]: 0BCBA3F64B: message-id=<20250113235333.0BCBA3F64B@localhost> 245s Jan 13 23:53:33 localhost postfix/qmgr[4679]: 0BCBA3F64B: from=, size=416, nrcpt=1 (queue active) 245s Jan 13 23:53:34 localhost postfix/local[4724]: 0BCBA3F64B: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:53:34 localhost postfix/qmgr[4679]: 0BCBA3F64B: removed 245s Jan 13 23:53:36 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: setting up TLS connection from localhost[127.0.0.1] 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:before SSL initialization 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:before SSL initialization 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS read client hello 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS write server hello 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS write change cipher spec 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:TLSv1.3 write encrypted extensions 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS write certificate 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:TLSv1.3 write server certificate verify 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS write finished 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:TLSv1.3 early data 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:TLSv1.3 early data 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS read finished 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736814215 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: SSL_accept:SSLv3/TLS write session ticket 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: 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 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: 1EEB63F64B: client=localhost[127.0.0.1] 245s Jan 13 23:53:36 localhost postfix/smtpd[4682]: 1EEB63F64B: 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]> 245s Jan 13 23:53:36 localhost postfix/cleanup[4723]: 1EEB63F64B: message-id=<20250113235336.1EEB63F64B@localhost> 245s Jan 13 23:53:36 localhost postfix/qmgr[4679]: 1EEB63F64B: from=, size=381, nrcpt=1 (queue active) 245s Jan 13 23:53:37 localhost postfix/local[4724]: 1EEB63F64B: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:53:37 localhost postfix/qmgr[4679]: 1EEB63F64B: removed 245s Jan 13 23:53:39 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 245s Jan 13 23:53:39 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:39 localhost postfix/smtpd[4685]: 36ED93F651: client=localhost[127.0.0.1] 245s Jan 13 23:53:39 localhost postfix/smtpd[4685]: 36ED93F651: 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]> 245s Jan 13 23:53:39 localhost postfix/cleanup[4723]: 36ED93F651: message-id=<20250113235339.36ED93F651@localhost> 245s Jan 13 23:53:39 localhost postfix/qmgr[4679]: 36ED93F651: from=, size=380, nrcpt=1 (queue active) 245s Jan 13 23:53:39 localhost postfix/cleanup[4723]: 377C43F652: message-id=<20250113235339.36ED93F651@localhost> 245s Jan 13 23:53:39 localhost postfix/local[4724]: 36ED93F651: to=, orig_to=, relay=local, delay=0, delays=0/0/0/0, dsn=2.0.0, status=sent (forwarded as 377C43F652) 245s Jan 13 23:53:39 localhost postfix/qmgr[4679]: 377C43F652: from=, size=503, nrcpt=1 (queue active) 245s Jan 13 23:53:39 localhost postfix/qmgr[4679]: 36ED93F651: removed 245s Jan 13 23:53:40 localhost postfix/local[4724]: 377C43F652: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:53:40 localhost postfix/qmgr[4679]: 377C43F652: removed 245s Jan 13 23:53:42 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 245s Jan 13 23:53:42 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:42 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:53:42 localhost postfix/smtpd[4685]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:42 localhost postfix/smtpd[4685]: 564073F64B: client=localhost[127.0.0.1] 245s Jan 13 23:53:42 localhost postfix/cleanup[4723]: 564073F64B: message-id=<20250113235342.564073F64B@localhost> 245s Jan 13 23:53:42 localhost postfix/qmgr[4679]: 564073F64B: from=, size=389, nrcpt=1 (queue active) 245s Jan 13 23:53:44 localhost postfix/smtpd[4685]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 245s Jan 13 23:53:44 localhost postfix/smtpd[4682]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:44 localhost postfix/smtpd[4682]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:53:48 localhost postfix/postfix-script[4902]: stopping the Postfix mail system 245s Jan 13 23:53:48 localhost postfix/master[4677]: terminating on signal 15 245s Jan 13 23:53:49 localhost postfix/master[5329]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:53:49 localhost postfix/qmgr[5331]: 564073F64B: from=, size=389, nrcpt=1 (queue active) 245s Jan 13 23:53:49 localhost postfix/local[5335]: 564073F64B: to=, orig_to=, relay=local, delay=6.7, delays=6.7/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "twnpgzoe") 245s Jan 13 23:53:49 localhost postfix/cleanup[5338]: 0FF0E3F650: message-id=<20250113235349.0FF0E3F650@localhost> 245s Jan 13 23:53:49 localhost postfix/bounce[5336]: 564073F64B: sender non-delivery notification: 0FF0E3F650 245s Jan 13 23:53:49 localhost postfix/qmgr[5331]: 0FF0E3F650: from=<>, size=2190, nrcpt=1 (queue active) 245s Jan 13 23:53:49 localhost postfix/qmgr[5331]: 564073F64B: removed 245s Jan 13 23:53:50 localhost postfix/local[5335]: 0FF0E3F650: to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:53:50 localhost postfix/qmgr[5331]: 0FF0E3F650: removed 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: initializing the server-side TLS engine 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:51 localhost postfix/smtpd[5344]: initializing the server-side TLS engine 245s Jan 13 23:53:51 localhost postfix/smtpd[5344]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:51 localhost postfix/smtpd[5344]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:53:51 localhost postfix/smtpd[5344]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: warning: SASL authentication failure: incorrect digest response 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tupvopxl 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: 2BF093F64B: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tupvopxl 245s Jan 13 23:53:51 localhost postfix/smtpd[5341]: 2BF093F64B: 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]> 245s Jan 13 23:53:51 localhost postfix/cleanup[5338]: 2BF093F64B: message-id=<20250113235351.2BF093F64B@localhost> 245s Jan 13 23:53:51 localhost postfix/qmgr[5331]: 2BF093F64B: from=, size=416, nrcpt=1 (queue active) 245s Jan 13 23:53:52 localhost postfix/local[5335]: 2BF093F64B: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:53:52 localhost postfix/qmgr[5331]: 2BF093F64B: removed 245s Jan 13 23:53:54 localhost postfix/smtpd[5341]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 245s Jan 13 23:53:54 localhost postfix/smtpd[5344]: connect from localhost[127.0.0.1] 245s Jan 13 23:53:54 localhost postfix/smtpd[5344]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:53:58 localhost postfix/postfix-script[5382]: stopping the Postfix mail system 245s Jan 13 23:53:58 localhost postfix/master[5329]: terminating on signal 15 245s Jan 13 23:53:58 localhost postfix/master[5809]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:54:00 localhost postfix/smtpd[5814]: initializing the server-side TLS engine 245s Jan 13 23:54:00 localhost postfix/smtpd[5814]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:00 localhost postfix/smtpd[5817]: initializing the server-side TLS engine 245s Jan 13 23:54:00 localhost postfix/smtpd[5817]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:00 localhost postfix/smtpd[5817]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:54:00 localhost postfix/smtpd[5817]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:54:00 localhost postfix/smtpd[5814]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 245s Jan 13 23:54:01 localhost postfix/smtpd[5817]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:01 localhost postfix/smtpd[5817]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:54:05 localhost postfix/postfix-script[5853]: stopping the Postfix mail system 245s Jan 13 23:54:05 localhost postfix/master[5809]: terminating on signal 15 245s Jan 13 23:54:05 localhost postfix/master[6280]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:54:08 localhost postfix/smtpd[6285]: initializing the server-side TLS engine 245s Jan 13 23:54:08 localhost postfix/smtpd[6285]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: initializing the server-side TLS engine 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:54:08 localhost postfix/smtpd[6285]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:08 localhost postfix/smtpd[6288]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:54:12 localhost postfix/postfix-script[6324]: stopping the Postfix mail system 245s Jan 13 23:54:12 localhost postfix/master[6280]: terminating on signal 15 245s Jan 13 23:54:12 localhost postfix/master[6751]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: initializing the server-side TLS engine 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:15 localhost postfix/smtpd[6759]: initializing the server-side TLS engine 245s Jan 13 23:54:15 localhost postfix/smtpd[6759]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:15 localhost postfix/smtpd[6759]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:54:15 localhost postfix/smtpd[6759]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: warning: SASL authentication failure: Password verification failed 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: warning: SASL authentication failure: Password verification failed 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=txmvirzr 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: 190653F64D: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=txmvirzr 245s Jan 13 23:54:15 localhost postfix/smtpd[6756]: 190653F64D: 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]> 245s Jan 13 23:54:15 localhost postfix/cleanup[6761]: 190653F64D: message-id=<20250113235415.190653F64D@localhost> 245s Jan 13 23:54:15 localhost postfix/qmgr[6753]: 190653F64D: from=, size=416, nrcpt=1 (queue active) 245s Jan 13 23:54:16 localhost postfix/local[6762]: 190653F64D: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 245s Jan 13 23:54:16 localhost postfix/qmgr[6753]: 190653F64D: removed 245s Jan 13 23:54:18 localhost postfix/smtpd[6756]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 245s Jan 13 23:54:18 localhost postfix/smtpd[6759]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:18 localhost postfix/smtpd[6759]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s Jan 13 23:54:22 localhost postfix/postfix-script[6800]: stopping the Postfix mail system 245s Jan 13 23:54:22 localhost postfix/master[6751]: terminating on signal 15 245s Jan 13 23:54:23 localhost postfix/master[7228]: daemon started -- version 3.9.1, configuration /etc/postfix 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: initializing the server-side TLS engine 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:25 localhost postfix/smtpd[7238]: initializing the server-side TLS engine 245s Jan 13 23:54:25 localhost postfix/smtpd[7238]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:25 localhost postfix/smtpd[7238]: lost connection after CONNECT from localhost[127.0.0.1] 245s Jan 13 23:54:25 localhost postfix/smtpd[7238]: disconnect from localhost[127.0.0.1] commands=0/0 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: warning: SASL authentication failure: Parse error 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: lost connection after AUTH from localhost[127.0.0.1] 245s Jan 13 23:54:25 localhost postfix/smtpd[7235]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 245s Jan 13 23:54:25 localhost postfix/smtpd[7238]: connect from localhost[127.0.0.1] 245s Jan 13 23:54:26 localhost postfix/smtpd[7238]: disconnect from localhost[127.0.0.1] quit=1 commands=1 245s autopkgtest [23:54:26]: test postfix: -----------------------] 245s postfix PASS 245s autopkgtest [23:54:26]: test postfix: - - - - - - - - - - results - - - - - - - - - - 246s autopkgtest [23:54:27]: @@@@@@@@@@@@@@@@@@@@ summary 246s postfix PASS 251s nova [W] Using flock in prodstack6-s390x 251s flock: timeout while waiting to get lock 251s Creating nova instance adt-plucky-s390x-postfix-20250113-235021-juju-7f2275-prod-proposed-migration-environment-2-358f33d0-af84-4dac-8c1b-763bbc16ecc4 from image adt/ubuntu-plucky-s390x-server-20250113.img (UUID 82e8db15-2bd2-42bd-9fca-a7e20aa66215)... 251s nova [W] Timed out waiting for d0092e37-313a-47af-895b-38237facbb72 to get deleted.