0s autopkgtest [16:15:37]: starting date and time: 2025-01-17 16:15:37+0000 0s autopkgtest [16:15:37]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [16:15:37]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.uhz7a8e9/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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-15.secgroup --name adt-plucky-amd64-postfix-20250117-161537-juju-7f2275-prod-proposed-migration-environment-2-faa0c66b-c535-4269-946f-3800686e2319 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -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/ 50s autopkgtest [16:16:27]: testbed dpkg architecture: amd64 51s autopkgtest [16:16:28]: testbed apt version: 2.9.18 51s autopkgtest [16:16:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup 51s autopkgtest [16:16:28]: testbed release detected to be: None 52s autopkgtest [16:16:29]: updating testbed package index (apt update) 52s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 53s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 53s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 53s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 53s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [868 kB] 53s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 53s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.1 kB] 53s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [178 kB] 53s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [228 kB] 53s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [331 kB] 53s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 53s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 53s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [1095 kB] 53s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [468 kB] 53s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [4444 B] 53s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [24.9 kB] 53s Fetched 3337 kB in 1s (3337 kB/s) 54s Reading package lists... 55s + lsb_release --codename --short 55s + RELEASE=plucky 55s + cat 55s + [ plucky != trusty ] 55s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 55s Reading package lists... 55s Building dependency tree... 55s Reading state information... 55s Calculating upgrade... 56s The following packages will be upgraded: 56s libgudev-1.0-0 usb.ids 56s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s Need to get 239 kB of archives. 56s After this operation, 0 B of additional disk space will be used. 56s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 usb.ids all 2025.01.14-1 [223 kB] 56s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libgudev-1.0-0 amd64 1:238-6 [15.8 kB] 56s Fetched 239 kB in 0s (496 kB/s) 57s (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 ... 75824 files and directories currently installed.) 57s Preparing to unpack .../usb.ids_2025.01.14-1_all.deb ... 57s Unpacking usb.ids (2025.01.14-1) over (2024.12.04-1) ... 57s Preparing to unpack .../libgudev-1.0-0_1%3a238-6_amd64.deb ... 57s Unpacking libgudev-1.0-0:amd64 (1:238-6) over (1:238-5ubuntu1) ... 57s Setting up usb.ids (2025.01.14-1) ... 57s Setting up libgudev-1.0-0:amd64 (1:238-6) ... 57s Processing triggers for libc-bin (2.40-4ubuntu1) ... 57s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 57s + /usr/lib/apt/apt-helper analyze-pattern ?true 57s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 57s + grep -q trusty /etc/lsb-release 57s + [ ! -d /usr/share/doc/unattended-upgrades ] 57s + [ ! -d /usr/share/doc/lxd ] 57s + [ ! -d /usr/share/doc/lxd-client ] 57s + [ ! -d /usr/share/doc/snapd ] 57s + type iptables 57s + cat 57s + chmod 755 /etc/rc.local 57s + . /etc/rc.local 57s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 57s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 57s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 57s + uname -m 57s + [ x86_64 = ppc64le ] 57s + [ -d /run/systemd/system ] 57s + systemd-detect-virt --quiet --vm 57s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 57s + cat 57s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 57s + echo COMPRESS=lz4 57s + sync 58s autopkgtest [16:16:35]: upgrading testbed (apt dist-upgrade and autopurge) 58s Reading package lists... 58s Building dependency tree... 58s Reading state information... 58s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 58s Starting 2 pkgProblemResolver with broken count: 0 58s Done 59s Entering ResolveByKeep 59s 59s The following packages will be upgraded: 59s libldap-common libldap2 59s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s Need to get 242 kB of archives. 59s After this operation, 33.8 kB of additional disk space will be used. 59s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libldap-common all 2.6.9+dfsg-1~exp2ubuntu1 [33.2 kB] 60s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libldap2 amd64 2.6.9+dfsg-1~exp2ubuntu1 [209 kB] 60s Fetched 242 kB in 0s (535 kB/s) 60s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 75824 files and directories currently installed.) 60s Preparing to unpack .../libldap-common_2.6.9+dfsg-1~exp2ubuntu1_all.deb ... 60s Unpacking libldap-common (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 60s Preparing to unpack .../libldap2_2.6.9+dfsg-1~exp2ubuntu1_amd64.deb ... 60s Unpacking libldap2:amd64 (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 60s Setting up libldap-common (2.6.9+dfsg-1~exp2ubuntu1) ... 60s Installing new version of config file /etc/ldap/ldap.conf ... 60s Setting up libldap2:amd64 (2.6.9+dfsg-1~exp2ubuntu1) ... 60s Processing triggers for man-db (2.13.0-1) ... 60s Processing triggers for libc-bin (2.40-4ubuntu1) ... 61s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s Starting pkgProblemResolver with broken count: 0 61s Starting 2 pkgProblemResolver with broken count: 0 61s Done 62s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 64s autopkgtest [16:16:41]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 64s autopkgtest [16:16:41]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 71s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 71s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 71s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 71s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 71s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 71s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 71s gpgv: Can't check signature: No public key 71s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 71s autopkgtest [16:16:48]: testing package postfix version 3.9.1-9 72s autopkgtest [16:16:49]: build not needed 75s autopkgtest [16:16:52]: test postfix: preparing testbed 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Starting pkgProblemResolver with broken count: 0 76s Starting 2 pkgProblemResolver with broken count: 0 76s Done 76s The following NEW packages will be installed: 76s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 76s ssl-cert 77s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 77s Need to get 401 kB of archives. 77s After this operation, 1375 kB of additional disk space will be used. 77s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 db5.3-util amd64 5.3.28+dfsg2-9 [73.2 kB] 77s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 db-util all 1:5.3.21ubuntu2 [2412 B] 77s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 sasl2-bin amd64 2.1.28+dfsg1-8 [94.4 kB] 77s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 procmail amd64 3.24-1ubuntu3 [149 kB] 77s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-ptyprocess all 0.7.0-6 [14.8 kB] 77s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-pexpect all 4.9-3 [48.1 kB] 77s Get:7 http://ftpmaster.internal/ubuntu plucky/main amd64 ssl-cert all 1.1.3ubuntu1 [18.7 kB] 77s Preconfiguring packages ... 77s Fetched 401 kB in 0s (822 kB/s) 77s Selecting previously unselected package db5.3-util. 77s (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 ... 75823 files and directories currently installed.) 77s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_amd64.deb ... 77s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 77s Selecting previously unselected package db-util. 77s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 77s Unpacking db-util (1:5.3.21ubuntu2) ... 77s Selecting previously unselected package sasl2-bin. 77s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_amd64.deb ... 77s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 77s Selecting previously unselected package procmail. 77s Preparing to unpack .../3-procmail_3.24-1ubuntu3_amd64.deb ... 77s Unpacking procmail (3.24-1ubuntu3) ... 77s Selecting previously unselected package python3-ptyprocess. 77s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 77s Unpacking python3-ptyprocess (0.7.0-6) ... 77s Selecting previously unselected package python3-pexpect. 77s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 77s Unpacking python3-pexpect (4.9-3) ... 77s Selecting previously unselected package ssl-cert. 77s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 77s Unpacking ssl-cert (1.1.3ubuntu1) ... 78s Setting up ssl-cert (1.1.3ubuntu1) ... 78s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 78s Setting up db5.3-util (5.3.28+dfsg2-9) ... 78s Setting up procmail (3.24-1ubuntu3) ... 78s Setting up python3-ptyprocess (0.7.0-6) ... 78s Setting up db-util (1:5.3.21ubuntu2) ... 78s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 79s saslauthd.service is a disabled or a static unit, not starting it. 79s Setting up python3-pexpect (4.9-3) ... 79s Processing triggers for man-db (2.13.0-1) ... 81s autopkgtest [16:16:58]: test postfix: [----------------------- 81s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Suggested packages: 82s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 82s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 82s postfix-pgsql postfix-sqlite 82s The following NEW packages will be installed: 82s postfix 82s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 82s Need to get 1318 kB of archives. 82s After this operation, 4186 kB of additional disk space will be used. 82s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 postfix amd64 3.9.1-9 [1318 kB] 82s Preconfiguring packages ... 83s Fetched 1318 kB in 1s (2380 kB/s) 83s Selecting previously unselected package postfix. 83s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 75989 files and directories currently installed.) 83s Preparing to unpack .../postfix_3.9.1-9_amd64.deb ... 83s Unpacking postfix (3.9.1-9) ... 83s Setting up postfix (3.9.1-9) ... 83s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 83s info: Selecting UID from range 100 to 999 ... 83s 83s info: Selecting GID from range 100 to 999 ... 83s info: Adding system user `postfix' (UID 108) ... 83s info: Adding new group `postfix' (GID 112) ... 83s info: Adding new user `postfix' (UID 108) with group `postfix' ... 83s info: Not creating home directory `/var/spool/postfix'. 84s info: Selecting GID from range 100 to 999 ... 84s info: Adding group `postdrop' (GID 113) ... 84s Creating /etc/postfix/dynamicmaps.cf 84s setting myhostname: localhost 84s setting alias maps 84s setting alias database 84s mailname is not a fully qualified domain name. Not changing /etc/mailname. 84s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 84s setting relayhost: 84s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 84s setting mailbox_command 84s setting mailbox_size_limit: 0 84s setting recipient_delimiter: + 86s setting inet_interfaces: all 88s setting inet_protocols: all 88s /etc/aliases does not exist, creating it. 88s WARNING: /etc/aliases exists, but does not have a root alias. 88s 88s Postfix (main.cf) is now set up with a default configuration. If you need to 88s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 88s Postfix configuration values, see postconf(1). 88s 88s After modifying main.cf, be sure to run 'systemctl reload postfix'. 88s 90s Processing triggers for ufw (0.36.2-8) ... 90s Processing triggers for man-db (2.13.0-1) ... 91s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 91s Processing triggers for postfix (3.9.1-9) ... 91s Restarting postfix 95s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 164s ............... 164s ---------------------------------------------------------------------- 164s Ran 15 tests in 67.812s 164s 164s OK 164s ===== postfix.log contents: 164s Jan 17 16:17:12 localhost postfix/master[3286]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:17:12 localhost postfix/postfix-script[3534]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 164s Jan 17 16:17:15 localhost postfix/postfix-script[4290]: stopping the Postfix mail system 164s Jan 17 16:17:15 localhost postfix/master[3286]: terminating on signal 15 164s Jan 17 16:17:16 localhost postfix/master[4716]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: initializing the server-side TLS engine 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: initializing the server-side TLS engine 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: 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]> 164s Jan 17 16:17:18 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: 78DCD3EF05: client=localhost[127.0.0.1] 164s Jan 17 16:17:18 localhost postfix/smtpd[4721]: 78DCD3EF05: 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]> 164s Jan 17 16:17:18 localhost postfix/cleanup[4762]: 78DCD3EF05: message-id=<20250117161718.78DCD3EF05@localhost> 164s Jan 17 16:17:18 localhost postfix/qmgr[4718]: 78DCD3EF05: from=, size=380, nrcpt=1 (queue active) 164s Jan 17 16:17:19 localhost postfix/local[4763]: 78DCD3EF05: to=, orig_to=, relay=local, delay=1, delays=0.01/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 164s Jan 17 16:17:19 localhost postfix/qmgr[4718]: 78DCD3EF05: removed 164s Jan 17 16:17:21 localhost postfix/smtpd[4721]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 164s Jan 17 16:17:21 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:23 localhost postfix/smtpd[4724]: warning: SASL authentication failure: Password verification failed 164s Jan 17 16:17:23 localhost postfix/smtpd[4724]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 164s Jan 17 16:17:26 localhost postfix/smtpd[4724]: warning: SASL authentication failure: Password verification failed 164s Jan 17 16:17:26 localhost postfix/smtpd[4724]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tjjiyxnp 164s Jan 17 16:17:26 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 164s Jan 17 16:17:26 localhost postfix/smtpd[4721]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:26 localhost postfix/smtpd[4721]: 45AEB3EF05: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=twvxnrjc 164s Jan 17 16:17:26 localhost postfix/smtpd[4721]: 45AEB3EF05: 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]> 164s Jan 17 16:17:26 localhost postfix/cleanup[4762]: 45AEB3EF05: message-id=<20250117161726.45AEB3EF05@localhost> 164s Jan 17 16:17:26 localhost postfix/qmgr[4718]: 45AEB3EF05: from=, size=416, nrcpt=1 (queue active) 164s Jan 17 16:17:27 localhost postfix/local[4763]: 45AEB3EF05: 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") 164s Jan 17 16:17:27 localhost postfix/qmgr[4718]: 45AEB3EF05: removed 164s Jan 17 16:17:29 localhost postfix/smtpd[4721]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: setting up TLS connection from localhost[127.0.0.1] 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:before SSL initialization 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:before SSL initialization 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS read client hello 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS write server hello 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS write change cipher spec 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:TLSv1.3 write encrypted extensions 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS write certificate 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:TLSv1.3 write server certificate verify 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS write finished 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:TLSv1.3 early data 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:TLSv1.3 early data 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS read finished 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1737132448 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: SSL_accept:SSLv3/TLS write session ticket 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: 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 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: 64D5C3EF05: client=localhost[127.0.0.1] 164s Jan 17 16:17:29 localhost postfix/smtpd[4724]: 64D5C3EF05: 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]> 164s Jan 17 16:17:29 localhost postfix/cleanup[4762]: 64D5C3EF05: message-id=<20250117161729.64D5C3EF05@localhost> 164s Jan 17 16:17:29 localhost postfix/qmgr[4718]: 64D5C3EF05: from=, size=381, nrcpt=1 (queue active) 164s Jan 17 16:17:30 localhost postfix/local[4763]: 64D5C3EF05: 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") 164s Jan 17 16:17:30 localhost postfix/qmgr[4718]: 64D5C3EF05: removed 164s Jan 17 16:17:32 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 164s Jan 17 16:17:32 localhost postfix/smtpd[4721]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:32 localhost postfix/smtpd[4721]: ADCA13EF0B: client=localhost[127.0.0.1] 164s Jan 17 16:17:32 localhost postfix/smtpd[4721]: ADCA13EF0B: 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]> 164s Jan 17 16:17:32 localhost postfix/cleanup[4762]: ADCA13EF0B: message-id=<20250117161732.ADCA13EF0B@localhost> 164s Jan 17 16:17:32 localhost postfix/qmgr[4718]: ADCA13EF0B: from=, size=380, nrcpt=1 (queue active) 164s Jan 17 16:17:32 localhost postfix/cleanup[4762]: AF1103EF0C: message-id=<20250117161732.ADCA13EF0B@localhost> 164s Jan 17 16:17:32 localhost postfix/qmgr[4718]: AF1103EF0C: from=, size=503, nrcpt=1 (queue active) 164s Jan 17 16:17:32 localhost postfix/local[4763]: ADCA13EF0B: to=, orig_to=, relay=local, delay=0.01, delays=0.01/0/0/0, dsn=2.0.0, status=sent (forwarded as AF1103EF0C) 164s Jan 17 16:17:32 localhost postfix/qmgr[4718]: ADCA13EF0B: removed 164s Jan 17 16:17:33 localhost postfix/local[4763]: AF1103EF0C: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 164s Jan 17 16:17:33 localhost postfix/qmgr[4718]: AF1103EF0C: removed 164s Jan 17 16:17:35 localhost postfix/smtpd[4721]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 164s Jan 17 16:17:35 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:35 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:17:36 localhost postfix/smtpd[4721]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:36 localhost postfix/smtpd[4721]: 040753EF05: client=localhost[127.0.0.1] 164s Jan 17 16:17:36 localhost postfix/cleanup[4762]: 040753EF05: message-id=<20250117161736.040753EF05@localhost> 164s Jan 17 16:17:36 localhost postfix/qmgr[4718]: 040753EF05: from=, size=389, nrcpt=1 (queue active) 164s Jan 17 16:17:38 localhost postfix/smtpd[4721]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 164s Jan 17 16:17:38 localhost postfix/smtpd[4724]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:38 localhost postfix/smtpd[4724]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:17:42 localhost postfix/postfix-script[4941]: stopping the Postfix mail system 164s Jan 17 16:17:42 localhost postfix/master[4716]: terminating on signal 15 164s Jan 17 16:17:43 localhost postfix/master[5368]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:17:43 localhost postfix/qmgr[5370]: 040753EF05: from=, size=389, nrcpt=1 (queue active) 164s Jan 17 16:17:43 localhost postfix/local[5374]: 040753EF05: to=, orig_to=, relay=local, delay=7.6, delays=7.6/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "tkpdnyks") 164s Jan 17 16:17:43 localhost postfix/cleanup[5376]: 922523EF0A: message-id=<20250117161743.922523EF0A@localhost> 164s Jan 17 16:17:43 localhost postfix/bounce[5375]: 040753EF05: sender non-delivery notification: 922523EF0A 164s Jan 17 16:17:43 localhost postfix/qmgr[5370]: 922523EF0A: from=<>, size=2190, nrcpt=1 (queue active) 164s Jan 17 16:17:43 localhost postfix/qmgr[5370]: 040753EF05: removed 164s Jan 17 16:17:44 localhost postfix/local[5374]: 922523EF0A: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 164s Jan 17 16:17:44 localhost postfix/qmgr[5370]: 922523EF0A: removed 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: initializing the server-side TLS engine 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:45 localhost postfix/smtpd[5382]: initializing the server-side TLS engine 164s Jan 17 16:17:45 localhost postfix/smtpd[5382]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:45 localhost postfix/smtpd[5382]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:17:45 localhost postfix/smtpd[5382]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: warning: SASL authentication failure: incorrect digest response 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=trkgqojy 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: AD98C3EF05: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=trkgqojy 164s Jan 17 16:17:45 localhost postfix/smtpd[5379]: AD98C3EF05: 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]> 164s Jan 17 16:17:45 localhost postfix/cleanup[5376]: AD98C3EF05: message-id=<20250117161745.AD98C3EF05@localhost> 164s Jan 17 16:17:45 localhost postfix/qmgr[5370]: AD98C3EF05: from=, size=416, nrcpt=1 (queue active) 164s Jan 17 16:17:46 localhost postfix/local[5374]: AD98C3EF05: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 164s Jan 17 16:17:46 localhost postfix/qmgr[5370]: AD98C3EF05: removed 164s Jan 17 16:17:48 localhost postfix/smtpd[5379]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 164s Jan 17 16:17:48 localhost postfix/smtpd[5382]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:48 localhost postfix/smtpd[5382]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:17:52 localhost postfix/postfix-script[5420]: stopping the Postfix mail system 164s Jan 17 16:17:52 localhost postfix/master[5368]: terminating on signal 15 164s Jan 17 16:17:53 localhost postfix/master[5847]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:17:55 localhost postfix/smtpd[5852]: initializing the server-side TLS engine 164s Jan 17 16:17:55 localhost postfix/smtpd[5852]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: initializing the server-side TLS engine 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:17:55 localhost postfix/smtpd[5852]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: connect from localhost[127.0.0.1] 164s Jan 17 16:17:55 localhost postfix/smtpd[5855]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:17:59 localhost postfix/postfix-script[5891]: stopping the Postfix mail system 164s Jan 17 16:17:59 localhost postfix/master[5847]: terminating on signal 15 164s Jan 17 16:18:00 localhost postfix/master[6318]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:18:02 localhost postfix/smtpd[6323]: initializing the server-side TLS engine 164s Jan 17 16:18:02 localhost postfix/smtpd[6323]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: initializing the server-side TLS engine 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:18:02 localhost postfix/smtpd[6323]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:02 localhost postfix/smtpd[6326]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:18:06 localhost postfix/postfix-script[6362]: stopping the Postfix mail system 164s Jan 17 16:18:06 localhost postfix/master[6318]: terminating on signal 15 164s Jan 17 16:18:07 localhost postfix/master[6789]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: initializing the server-side TLS engine 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:09 localhost postfix/smtpd[6797]: initializing the server-side TLS engine 164s Jan 17 16:18:09 localhost postfix/smtpd[6797]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:09 localhost postfix/smtpd[6797]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:18:09 localhost postfix/smtpd[6797]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: warning: SASL authentication failure: Password verification failed 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: warning: SASL authentication failure: Password verification failed 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tpkbulno 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: 86B083EF07: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tpkbulno 164s Jan 17 16:18:09 localhost postfix/smtpd[6794]: 86B083EF07: 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]> 164s Jan 17 16:18:09 localhost postfix/cleanup[6799]: 86B083EF07: message-id=<20250117161809.86B083EF07@localhost> 164s Jan 17 16:18:09 localhost postfix/qmgr[6791]: 86B083EF07: from=, size=416, nrcpt=1 (queue active) 164s Jan 17 16:18:10 localhost postfix/local[6801]: 86B083EF07: 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") 164s Jan 17 16:18:10 localhost postfix/qmgr[6791]: 86B083EF07: removed 164s Jan 17 16:18:12 localhost postfix/smtpd[6794]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 164s Jan 17 16:18:12 localhost postfix/smtpd[6797]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:12 localhost postfix/smtpd[6797]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s Jan 17 16:18:16 localhost postfix/postfix-script[6839]: stopping the Postfix mail system 164s Jan 17 16:18:16 localhost postfix/master[6789]: terminating on signal 15 164s Jan 17 16:18:17 localhost postfix/master[7266]: daemon started -- version 3.9.1, configuration /etc/postfix 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: initializing the server-side TLS engine 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:19 localhost postfix/smtpd[7276]: initializing the server-side TLS engine 164s Jan 17 16:18:19 localhost postfix/smtpd[7276]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:19 localhost postfix/smtpd[7276]: lost connection after CONNECT from localhost[127.0.0.1] 164s Jan 17 16:18:19 localhost postfix/smtpd[7276]: disconnect from localhost[127.0.0.1] commands=0/0 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: warning: SASL authentication failure: Parse error 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: lost connection after AUTH from localhost[127.0.0.1] 164s Jan 17 16:18:19 localhost postfix/smtpd[7273]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 164s Jan 17 16:18:19 localhost postfix/smtpd[7276]: connect from localhost[127.0.0.1] 164s Jan 17 16:18:21 localhost postfix/smtpd[7276]: disconnect from localhost[127.0.0.1] quit=1 commands=1 164s autopkgtest [16:18:21]: test postfix: -----------------------] 165s autopkgtest [16:18:22]: test postfix: - - - - - - - - - - results - - - - - - - - - - 165s postfix PASS 165s autopkgtest [16:18:22]: @@@@@@@@@@@@@@@@@@@@ summary 165s postfix PASS 171s nova [W] Skipping flock for amd64 171s Creating nova instance adt-plucky-amd64-postfix-20250117-161537-juju-7f2275-prod-proposed-migration-environment-2-faa0c66b-c535-4269-946f-3800686e2319 from image adt/ubuntu-plucky-amd64-server-20250117.img (UUID 8f35863a-398a-4b7d-9594-544c7b7656c3)... 171s nova [W] Timed out waiting for 98a4409d-28d9-438c-b0e9-bf412865f473 to get deleted.