0s autopkgtest [00:25:16]: starting date and time: 2025-01-14 00:25:16+0000 0s autopkgtest [00:25:16]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [00:25:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.j0b84w9e/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-23.secgroup --name adt-plucky-ppc64el-postfix-20250113-235013-juju-7f2275-prod-proposed-migration-environment-2-4a79aaa5-8e5d-4b81-a321-21f0389247b8 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -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/ 131s autopkgtest [00:27:27]: testbed dpkg architecture: ppc64el 131s autopkgtest [00:27:27]: testbed apt version: 2.9.18 131s autopkgtest [00:27:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 131s autopkgtest [00:27:27]: testbed release detected to be: None 132s autopkgtest [00:27:28]: updating testbed package index (apt update) 132s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 133s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 133s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 133s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 133s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [818 kB] 133s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 133s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [10.3 kB] 133s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [134 kB] 133s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [254 kB] 133s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 133s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [956 kB] 133s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [4080 B] 133s Fetched 2260 kB in 1s (2087 kB/s) 134s Reading package lists... 135s + lsb_release --codename --short 135s + RELEASE=plucky 135s + cat 135s + [ plucky != trusty ] 135s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s Calculating upgrade... 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 135s + /usr/lib/apt/apt-helper analyze-pattern ?true 135s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 135s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s + grep -q trusty /etc/lsb-release 136s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 136s + [ ! -d /usr/share/doc/unattended-upgrades ] 136s + [ ! -d /usr/share/doc/lxd ] 136s + [ ! -d /usr/share/doc/lxd-client ] 136s + [ ! -d /usr/share/doc/snapd ] 136s + type iptables 136s + cat 136s + chmod 755 /etc/rc.local 136s + . /etc/rc.local 136s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 136s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 136s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 136s + uname -m 136s + [ ppc64le = ppc64le ] 136s + systemctl is-active keyboard-setup.service 136s + [ active = failed ] 136s + [ -d /run/systemd/system ] 136s + systemd-detect-virt --quiet --vm 136s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 136s + cat 136s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 136s + echo COMPRESS=lz4 136s autopkgtest [00:27:32]: upgrading testbed (apt dist-upgrade and autopurge) 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 137s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 137s Starting 2 pkgProblemResolver with broken count: 0 137s Done 137s Entering ResolveByKeep 137s 137s The following packages will be upgraded: 137s libldap-common libldap2 137s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 137s Need to get 269 kB of archives. 137s After this operation, 1024 B of additional disk space will be used. 137s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libldap-common all 2.6.9+dfsg-1~exp2ubuntu1 [33.2 kB] 138s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libldap2 ppc64el 2.6.9+dfsg-1~exp2ubuntu1 [236 kB] 138s Fetched 269 kB in 0s (658 kB/s) 138s (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 ... 74029 files and directories currently installed.) 138s Preparing to unpack .../libldap-common_2.6.9+dfsg-1~exp2ubuntu1_all.deb ... 138s Unpacking libldap-common (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 138s Preparing to unpack .../libldap2_2.6.9+dfsg-1~exp2ubuntu1_ppc64el.deb ... 138s Unpacking libldap2:ppc64el (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 138s Setting up libldap-common (2.6.9+dfsg-1~exp2ubuntu1) ... 138s Installing new version of config file /etc/ldap/ldap.conf ... 138s Setting up libldap2:ppc64el (2.6.9+dfsg-1~exp2ubuntu1) ... 138s Processing triggers for man-db (2.13.0-1) ... 139s Processing triggers for libc-bin (2.40-4ubuntu1) ... 139s Reading package lists... 139s Building dependency tree... 139s Reading state information... 139s Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 140s Done 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s autopkgtest [00:27:38]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 142s autopkgtest [00:27:38]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 149s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 149s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 149s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 149s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 149s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 149s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 149s gpgv: Can't check signature: No public key 149s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 149s autopkgtest [00:27:45]: testing package postfix version 3.9.1-9 150s autopkgtest [00:27:46]: build not needed 152s autopkgtest [00:27:48]: test postfix: preparing testbed 152s Reading package lists... 152s Building dependency tree... 152s Reading state information... 152s Starting pkgProblemResolver with broken count: 0 152s Starting 2 pkgProblemResolver with broken count: 0 152s Done 153s The following NEW packages will be installed: 153s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 153s ssl-cert 153s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 153s Need to get 428 kB of archives. 153s After this operation, 2611 kB of additional disk space will be used. 153s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el db5.3-util ppc64el 5.3.28+dfsg2-9 [78.3 kB] 153s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el db-util all 1:5.3.21ubuntu2 [2412 B] 153s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el sasl2-bin ppc64el 2.1.28+dfsg1-8 [101 kB] 153s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el procmail ppc64el 3.24-1ubuntu3 [165 kB] 153s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-ptyprocess all 0.7.0-6 [14.8 kB] 153s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pexpect all 4.9-3 [48.1 kB] 153s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ssl-cert all 1.1.3ubuntu1 [18.7 kB] 153s Preconfiguring packages ... 153s Fetched 428 kB in 0s (890 kB/s) 153s Selecting previously unselected package db5.3-util. 153s (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 ... 74028 files and directories currently installed.) 153s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_ppc64el.deb ... 153s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 153s Selecting previously unselected package db-util. 153s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 153s Unpacking db-util (1:5.3.21ubuntu2) ... 153s Selecting previously unselected package sasl2-bin. 153s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_ppc64el.deb ... 153s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 153s Selecting previously unselected package procmail. 153s Preparing to unpack .../3-procmail_3.24-1ubuntu3_ppc64el.deb ... 153s Unpacking procmail (3.24-1ubuntu3) ... 154s Selecting previously unselected package python3-ptyprocess. 154s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 154s Unpacking python3-ptyprocess (0.7.0-6) ... 154s Selecting previously unselected package python3-pexpect. 154s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 154s Unpacking python3-pexpect (4.9-3) ... 154s Selecting previously unselected package ssl-cert. 154s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 154s Unpacking ssl-cert (1.1.3ubuntu1) ... 154s Setting up ssl-cert (1.1.3ubuntu1) ... 154s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 154s Setting up db5.3-util (5.3.28+dfsg2-9) ... 154s Setting up procmail (3.24-1ubuntu3) ... 154s Setting up python3-ptyprocess (0.7.0-6) ... 154s Setting up db-util (1:5.3.21ubuntu2) ... 154s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 155s saslauthd.service is a disabled or a static unit, not starting it. 155s Setting up python3-pexpect (4.9-3) ... 155s Processing triggers for man-db (2.13.0-1) ... 158s autopkgtest [00:27:54]: test postfix: [----------------------- 158s Reading package lists... 158s Building dependency tree... 158s Reading state information... 159s The following additional packages will be installed: 159s libicu74 159s Suggested packages: 159s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 159s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 159s postfix-pgsql postfix-sqlite 159s The following NEW packages will be installed: 159s libicu74 postfix 159s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 159s Need to get 12.6 MB of archives. 159s After this operation, 45.5 MB of additional disk space will be used. 159s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libicu74 ppc64el 74.2-1ubuntu4 [11.2 MB] 160s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el postfix ppc64el 3.9.1-9 [1390 kB] 160s Preconfiguring packages ... 160s Fetched 12.6 MB in 1s (13.4 MB/s) 160s Selecting previously unselected package libicu74:ppc64el. 160s (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 ... 74194 files and directories currently installed.) 160s Preparing to unpack .../libicu74_74.2-1ubuntu4_ppc64el.deb ... 160s Unpacking libicu74:ppc64el (74.2-1ubuntu4) ... 160s Selecting previously unselected package postfix. 160s Preparing to unpack .../postfix_3.9.1-9_ppc64el.deb ... 160s Unpacking postfix (3.9.1-9) ... 161s Setting up libicu74:ppc64el (74.2-1ubuntu4) ... 161s Setting up postfix (3.9.1-9) ... 161s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 161s info: Selecting UID from range 100 to 999 ... 161s 161s info: Selecting GID from range 100 to 999 ... 161s info: Adding system user `postfix' (UID 107) ... 161s info: Adding new group `postfix' (GID 111) ... 161s info: Adding new user `postfix' (UID 107) with group `postfix' ... 161s info: Not creating home directory `/var/spool/postfix'. 161s info: Selecting GID from range 100 to 999 ... 161s info: Adding group `postdrop' (GID 112) ... 161s Creating /etc/postfix/dynamicmaps.cf 162s setting myhostname: localhost 162s setting alias maps 162s setting alias database 162s mailname is not a fully qualified domain name. Not changing /etc/mailname. 162s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 162s setting relayhost: 162s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 162s setting mailbox_command 162s setting mailbox_size_limit: 0 162s setting recipient_delimiter: + 164s setting inet_interfaces: all 166s setting inet_protocols: all 166s /etc/aliases does not exist, creating it. 166s WARNING: /etc/aliases exists, but does not have a root alias. 166s 166s Postfix (main.cf) is now set up with a default configuration. If you need to 166s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 166s Postfix configuration values, see postconf(1). 166s 166s After modifying main.cf, be sure to run 'systemctl reload postfix'. 166s 168s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 168s Processing triggers for ufw (0.36.2-8) ... 168s Processing triggers for man-db (2.13.0-1) ... 169s Processing triggers for libc-bin (2.40-4ubuntu1) ... 169s Processing triggers for postfix (3.9.1-9) ... 169s Restarting postfix 174s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 246s ............... 246s ---------------------------------------------------------------------- 246s Ran 15 tests in 71.535s 246s 246s OK 246s ===== postfix.log contents: 246s Jan 14 00:28:10 localhost postfix/master[3426]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:28:10 localhost postfix/postfix-script[3674]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 246s Jan 14 00:28:13 localhost postfix/postfix-script[4430]: stopping the Postfix mail system 246s Jan 14 00:28:13 localhost postfix/master[3426]: terminating on signal 15 246s Jan 14 00:28:14 localhost postfix/master[4856]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: initializing the server-side TLS engine 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: initializing the server-side TLS engine 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: 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]> 246s Jan 14 00:28:16 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: EFD863F119: client=localhost[127.0.0.1] 246s Jan 14 00:28:16 localhost postfix/smtpd[4864]: EFD863F119: 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]> 246s Jan 14 00:28:16 localhost postfix/cleanup[4902]: EFD863F119: message-id=<20250114002816.EFD863F119@localhost> 246s Jan 14 00:28:16 localhost postfix/qmgr[4858]: EFD863F119: from=, size=380, nrcpt=1 (queue active) 246s Jan 14 00:28:17 localhost postfix/local[4903]: EFD863F119: 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") 246s Jan 14 00:28:17 localhost postfix/qmgr[4858]: EFD863F119: removed 246s Jan 14 00:28:19 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 246s Jan 14 00:28:20 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:21 localhost postfix/smtpd[4861]: warning: SASL authentication failure: Password verification failed 246s Jan 14 00:28:21 localhost postfix/smtpd[4861]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 246s Jan 14 00:28:23 localhost postfix/smtpd[4861]: warning: SASL authentication failure: Password verification failed 246s Jan 14 00:28:23 localhost postfix/smtpd[4861]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tynedcmv 246s Jan 14 00:28:23 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 246s Jan 14 00:28:23 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:24 localhost postfix/smtpd[4864]: 01B6E3F119: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=thcpkppp 246s Jan 14 00:28:24 localhost postfix/smtpd[4864]: 01B6E3F119: 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]> 246s Jan 14 00:28:24 localhost postfix/cleanup[4902]: 01B6E3F119: message-id=<20250114002824.01B6E3F119@localhost> 246s Jan 14 00:28:24 localhost postfix/qmgr[4858]: 01B6E3F119: from=, size=416, nrcpt=1 (queue active) 246s Jan 14 00:28:25 localhost postfix/local[4903]: 01B6E3F119: 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") 246s Jan 14 00:28:25 localhost postfix/qmgr[4858]: 01B6E3F119: removed 246s Jan 14 00:28:27 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: setting up TLS connection from localhost[127.0.0.1] 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:before SSL initialization 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:before SSL initialization 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS read client hello 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS write server hello 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS write change cipher spec 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:TLSv1.3 write encrypted extensions 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS write certificate 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:TLSv1.3 write server certificate verify 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS write finished 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:TLSv1.3 early data 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:TLSv1.3 early data 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS read finished 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736816306 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: SSL_accept:SSLv3/TLS write session ticket 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: 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 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: 342713F119: client=localhost[127.0.0.1] 246s Jan 14 00:28:27 localhost postfix/smtpd[4861]: 342713F119: 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]> 246s Jan 14 00:28:27 localhost postfix/cleanup[4902]: 342713F119: message-id=<20250114002827.342713F119@localhost> 246s Jan 14 00:28:27 localhost postfix/qmgr[4858]: 342713F119: from=, size=381, nrcpt=1 (queue active) 246s Jan 14 00:28:28 localhost postfix/local[4903]: 342713F119: 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") 246s Jan 14 00:28:28 localhost postfix/qmgr[4858]: 342713F119: removed 246s Jan 14 00:28:30 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 246s Jan 14 00:28:30 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:30 localhost postfix/smtpd[4864]: 69F413F11F: client=localhost[127.0.0.1] 246s Jan 14 00:28:30 localhost postfix/smtpd[4864]: 69F413F11F: 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]> 246s Jan 14 00:28:30 localhost postfix/cleanup[4902]: 69F413F11F: message-id=<20250114002830.69F413F11F@localhost> 246s Jan 14 00:28:30 localhost postfix/qmgr[4858]: 69F413F11F: from=, size=380, nrcpt=1 (queue active) 246s Jan 14 00:28:30 localhost postfix/cleanup[4902]: 6CEA53F120: message-id=<20250114002830.69F413F11F@localhost> 246s Jan 14 00:28:30 localhost postfix/qmgr[4858]: 6CEA53F120: from=, size=503, nrcpt=1 (queue active) 246s Jan 14 00:28:30 localhost postfix/local[4903]: 69F413F11F: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0, dsn=2.0.0, status=sent (forwarded as 6CEA53F120) 246s Jan 14 00:28:30 localhost postfix/qmgr[4858]: 69F413F11F: removed 246s Jan 14 00:28:31 localhost postfix/local[4903]: 6CEA53F120: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 246s Jan 14 00:28:31 localhost postfix/qmgr[4858]: 6CEA53F120: removed 246s Jan 14 00:28:33 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 246s Jan 14 00:28:33 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:33 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:28:33 localhost postfix/smtpd[4864]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:33 localhost postfix/smtpd[4864]: C3C4C3F119: client=localhost[127.0.0.1] 246s Jan 14 00:28:33 localhost postfix/cleanup[4902]: C3C4C3F119: message-id=<20250114002833.C3C4C3F119@localhost> 246s Jan 14 00:28:33 localhost postfix/qmgr[4858]: C3C4C3F119: from=, size=389, nrcpt=1 (queue active) 246s Jan 14 00:28:35 localhost postfix/smtpd[4864]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 246s Jan 14 00:28:35 localhost postfix/smtpd[4861]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:35 localhost postfix/smtpd[4861]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:28:39 localhost postfix/postfix-script[5082]: stopping the Postfix mail system 246s Jan 14 00:28:39 localhost postfix/master[4856]: terminating on signal 15 246s Jan 14 00:28:40 localhost postfix/master[5509]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:28:40 localhost postfix/qmgr[5511]: C3C4C3F119: from=, size=389, nrcpt=1 (queue active) 246s Jan 14 00:28:40 localhost postfix/local[5515]: C3C4C3F119: to=, orig_to=, relay=local, delay=7.1, delays=7/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "tkdespmi") 246s Jan 14 00:28:40 localhost postfix/cleanup[5517]: D1B5A3F11E: message-id=<20250114002840.D1B5A3F11E@localhost> 246s Jan 14 00:28:40 localhost postfix/bounce[5516]: C3C4C3F119: sender non-delivery notification: D1B5A3F11E 246s Jan 14 00:28:40 localhost postfix/qmgr[5511]: D1B5A3F11E: from=<>, size=2190, nrcpt=1 (queue active) 246s Jan 14 00:28:40 localhost postfix/qmgr[5511]: C3C4C3F119: removed 246s Jan 14 00:28:41 localhost postfix/local[5515]: D1B5A3F11E: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 246s Jan 14 00:28:41 localhost postfix/qmgr[5511]: D1B5A3F11E: removed 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: initializing the server-side TLS engine 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:42 localhost postfix/smtpd[5523]: initializing the server-side TLS engine 246s Jan 14 00:28:42 localhost postfix/smtpd[5523]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:42 localhost postfix/smtpd[5523]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:28:42 localhost postfix/smtpd[5523]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: warning: SASL authentication failure: incorrect digest response 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tvjxlgly 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: EEAD03F119: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tvjxlgly 246s Jan 14 00:28:42 localhost postfix/smtpd[5520]: EEAD03F119: 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]> 246s Jan 14 00:28:42 localhost postfix/cleanup[5517]: EEAD03F119: message-id=<20250114002842.EEAD03F119@localhost> 246s Jan 14 00:28:42 localhost postfix/qmgr[5511]: EEAD03F119: from=, size=416, nrcpt=1 (queue active) 246s Jan 14 00:28:43 localhost postfix/local[5515]: EEAD03F119: 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") 246s Jan 14 00:28:43 localhost postfix/qmgr[5511]: EEAD03F119: removed 246s Jan 14 00:28:45 localhost postfix/smtpd[5520]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 246s Jan 14 00:28:46 localhost postfix/smtpd[5523]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:46 localhost postfix/smtpd[5523]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:28:50 localhost postfix/postfix-script[5561]: stopping the Postfix mail system 246s Jan 14 00:28:50 localhost postfix/master[5509]: terminating on signal 15 246s Jan 14 00:28:52 localhost postfix/master[5988]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:28:54 localhost postfix/smtpd[5993]: initializing the server-side TLS engine 246s Jan 14 00:28:54 localhost postfix/smtpd[5993]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: initializing the server-side TLS engine 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:28:54 localhost postfix/smtpd[5993]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: connect from localhost[127.0.0.1] 246s Jan 14 00:28:54 localhost postfix/smtpd[5996]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:28:58 localhost postfix/postfix-script[6032]: stopping the Postfix mail system 246s Jan 14 00:28:58 localhost postfix/master[5988]: terminating on signal 15 246s Jan 14 00:28:59 localhost postfix/master[6459]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:29:02 localhost postfix/smtpd[6464]: initializing the server-side TLS engine 246s Jan 14 00:29:02 localhost postfix/smtpd[6464]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: initializing the server-side TLS engine 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:29:02 localhost postfix/smtpd[6464]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:02 localhost postfix/smtpd[6467]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:29:06 localhost postfix/postfix-script[6504]: stopping the Postfix mail system 246s Jan 14 00:29:06 localhost postfix/master[6459]: terminating on signal 15 246s Jan 14 00:29:08 localhost postfix/master[6931]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: initializing the server-side TLS engine 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:10 localhost postfix/smtpd[6939]: initializing the server-side TLS engine 246s Jan 14 00:29:10 localhost postfix/smtpd[6939]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: warning: SASL authentication failure: Password verification failed 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: warning: SASL authentication failure: Password verification failed 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tcbnccfk 246s Jan 14 00:29:10 localhost postfix/smtpd[6939]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:29:10 localhost postfix/smtpd[6939]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: 2FAC83F11B: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tcbnccfk 246s Jan 14 00:29:10 localhost postfix/smtpd[6936]: 2FAC83F11B: 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]> 246s Jan 14 00:29:10 localhost postfix/cleanup[6941]: 2FAC83F11B: message-id=<20250114002910.2FAC83F11B@localhost> 246s Jan 14 00:29:10 localhost postfix/qmgr[6933]: 2FAC83F11B: from=, size=416, nrcpt=1 (queue active) 246s Jan 14 00:29:11 localhost postfix/local[6943]: 2FAC83F11B: 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") 246s Jan 14 00:29:11 localhost postfix/qmgr[6933]: 2FAC83F11B: removed 246s Jan 14 00:29:13 localhost postfix/smtpd[6936]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 246s Jan 14 00:29:13 localhost postfix/smtpd[6939]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:13 localhost postfix/smtpd[6939]: disconnect from localhost[127.0.0.1] quit=1 commands=1 246s Jan 14 00:29:17 localhost postfix/postfix-script[6981]: stopping the Postfix mail system 246s Jan 14 00:29:17 localhost postfix/master[6931]: terminating on signal 15 246s Jan 14 00:29:18 localhost postfix/master[7408]: daemon started -- version 3.9.1, configuration /etc/postfix 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: initializing the server-side TLS engine 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:20 localhost postfix/smtpd[7418]: initializing the server-side TLS engine 246s Jan 14 00:29:20 localhost postfix/smtpd[7418]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:20 localhost postfix/smtpd[7418]: lost connection after CONNECT from localhost[127.0.0.1] 246s Jan 14 00:29:20 localhost postfix/smtpd[7418]: disconnect from localhost[127.0.0.1] commands=0/0 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: warning: SASL authentication failure: Parse error 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: lost connection after AUTH from localhost[127.0.0.1] 246s Jan 14 00:29:20 localhost postfix/smtpd[7415]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 246s Jan 14 00:29:21 localhost postfix/smtpd[7418]: connect from localhost[127.0.0.1] 246s Jan 14 00:29:22 localhost postfix/smtpd[7418]: disconnect from localhost[127.0.0.1] quit=1 commands=1 247s autopkgtest [00:29:23]: test postfix: -----------------------] 247s postfix PASS 247s autopkgtest [00:29:23]: test postfix: - - - - - - - - - - results - - - - - - - - - - 247s autopkgtest [00:29:23]: @@@@@@@@@@@@@@@@@@@@ summary 247s postfix PASS 264s nova [W] Using flock in prodstack6-ppc64el 264s Creating nova instance adt-plucky-ppc64el-postfix-20250113-235013-juju-7f2275-prod-proposed-migration-environment-2-4a79aaa5-8e5d-4b81-a321-21f0389247b8 from image adt/ubuntu-plucky-ppc64el-server-20250113.img (UUID 0698fc05-7aeb-41f0-8bcd-0b9b229fb140)... 264s nova [W] Timed out waiting for 7c149e89-887b-4f42-b765-d337cf530577 to get deleted.