0s autopkgtest [23:49:55]: starting date and time: 2025-01-13 23:49:55+0000 1s autopkgtest [23:49:56]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 1s autopkgtest [23:49:56]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.qxnkcoia/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-26.secgroup --name adt-plucky-arm64-postfix-20250113-234954-juju-7f2275-prod-proposed-migration-environment-20-07c1bfbf-0844-4bb2-a44e-ba0f14b8459d --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -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/ 156s autopkgtest [23:52:31]: testbed dpkg architecture: arm64 156s autopkgtest [23:52:31]: testbed apt version: 2.9.18 157s autopkgtest [23:52:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 157s autopkgtest [23:52:32]: testbed release detected to be: None 158s autopkgtest [23:52:33]: updating testbed package index (apt update) 158s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 158s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 158s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 158s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 158s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [134 kB] 159s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [10.3 kB] 159s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [818 kB] 159s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 159s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [270 kB] 159s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [57.8 kB] 159s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [983 kB] 159s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [10.2 kB] 159s Fetched 2367 kB in 1s (2449 kB/s) 160s Reading package lists... 160s Reading package lists... 161s Building dependency tree... 161s Reading state information... 161s Calculating upgrade... 162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 163s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 163s autopkgtest [23:52:38]: upgrading testbed (apt dist-upgrade and autopurge) 163s Reading package lists... 163s Building dependency tree... 163s Reading state information... 164s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 164s Starting 2 pkgProblemResolver with broken count: 0 164s Done 165s Entering ResolveByKeep 165s 165s The following packages will be upgraded: 165s libldap-common libldap2 166s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 166s Need to get 232 kB of archives. 166s After this operation, 1024 B of additional disk space will be used. 166s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libldap-common all 2.6.9+dfsg-1~exp2ubuntu1 [33.2 kB] 166s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libldap2 arm64 2.6.9+dfsg-1~exp2ubuntu1 [198 kB] 166s Fetched 232 kB in 0s (555 kB/s) 167s (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 ... 80189 files and directories currently installed.) 167s Preparing to unpack .../libldap-common_2.6.9+dfsg-1~exp2ubuntu1_all.deb ... 167s Unpacking libldap-common (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 167s Preparing to unpack .../libldap2_2.6.9+dfsg-1~exp2ubuntu1_arm64.deb ... 167s Unpacking libldap2:arm64 (2.6.9+dfsg-1~exp2ubuntu1) over (2.6.8+dfsg-1~exp4ubuntu3) ... 167s Setting up libldap-common (2.6.9+dfsg-1~exp2ubuntu1) ... 167s Installing new version of config file /etc/ldap/ldap.conf ... 167s Setting up libldap2:arm64 (2.6.9+dfsg-1~exp2ubuntu1) ... 167s Processing triggers for man-db (2.13.0-1) ... 167s Processing triggers for libc-bin (2.40-4ubuntu1) ... 167s Reading package lists... 168s Building dependency tree... 168s Reading state information... 168s Starting pkgProblemResolver with broken count: 0 168s Starting 2 pkgProblemResolver with broken count: 0 168s Done 169s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 171s autopkgtest [23:52:46]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 172s autopkgtest [23:52:47]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 179s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 179s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 179s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 179s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 179s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 179s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 179s gpgv: Can't check signature: No public key 179s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 180s autopkgtest [23:52:55]: testing package postfix version 3.9.1-9 181s autopkgtest [23:52:56]: build not needed 182s autopkgtest [23:52:57]: test postfix: preparing testbed 183s Reading package lists... 183s Building dependency tree... 183s Reading state information... 183s Starting pkgProblemResolver with broken count: 0 183s Starting 2 pkgProblemResolver with broken count: 0 183s Done 184s The following NEW packages will be installed: 184s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 184s ssl-cert 184s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 184s Need to get 399 kB of archives. 184s After this operation, 2545 kB of additional disk space will be used. 184s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 db5.3-util arm64 5.3.28+dfsg2-9 [72.1 kB] 184s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 db-util all 1:5.3.21ubuntu2 [2412 B] 184s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 sasl2-bin arm64 2.1.28+dfsg1-8 [95.0 kB] 185s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 procmail arm64 3.24-1ubuntu3 [147 kB] 185s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-ptyprocess all 0.7.0-6 [14.8 kB] 185s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-pexpect all 4.9-3 [48.1 kB] 185s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 ssl-cert all 1.1.3ubuntu1 [18.7 kB] 185s Preconfiguring packages ... 185s Fetched 399 kB in 0s (830 kB/s) 185s Selecting previously unselected package db5.3-util. 185s (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 ... 80188 files and directories currently installed.) 185s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_arm64.deb ... 185s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 185s Selecting previously unselected package db-util. 185s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 185s Unpacking db-util (1:5.3.21ubuntu2) ... 185s Selecting previously unselected package sasl2-bin. 185s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_arm64.deb ... 185s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 185s Selecting previously unselected package procmail. 185s Preparing to unpack .../3-procmail_3.24-1ubuntu3_arm64.deb ... 185s Unpacking procmail (3.24-1ubuntu3) ... 185s Selecting previously unselected package python3-ptyprocess. 185s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 185s Unpacking python3-ptyprocess (0.7.0-6) ... 185s Selecting previously unselected package python3-pexpect. 185s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 185s Unpacking python3-pexpect (4.9-3) ... 185s Selecting previously unselected package ssl-cert. 185s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 185s Unpacking ssl-cert (1.1.3ubuntu1) ... 185s Setting up ssl-cert (1.1.3ubuntu1) ... 186s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 186s Setting up db5.3-util (5.3.28+dfsg2-9) ... 186s Setting up procmail (3.24-1ubuntu3) ... 186s Setting up python3-ptyprocess (0.7.0-6) ... 186s Setting up db-util (1:5.3.21ubuntu2) ... 186s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 187s saslauthd.service is a disabled or a static unit, not starting it. 187s Setting up python3-pexpect (4.9-3) ... 187s Processing triggers for man-db (2.13.0-1) ... 189s autopkgtest [23:53:04]: test postfix: [----------------------- 190s Reading package lists... 190s Building dependency tree... 190s Reading state information... 191s The following additional packages will be installed: 191s libicu74 191s Suggested packages: 191s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 191s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 191s postfix-pgsql postfix-sqlite 191s The following NEW packages will be installed: 191s libicu74 postfix 191s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 191s Need to get 12.1 MB of archives. 191s After this operation, 43.1 MB of additional disk space will be used. 191s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libicu74 arm64 74.2-1ubuntu4 [10.8 MB] 192s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 postfix arm64 3.9.1-9 [1283 kB] 192s Preconfiguring packages ... 193s Fetched 12.1 MB in 1s (12.8 MB/s) 193s Selecting previously unselected package libicu74:arm64. 193s (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 ... 80354 files and directories currently installed.) 193s Preparing to unpack .../libicu74_74.2-1ubuntu4_arm64.deb ... 193s Unpacking libicu74:arm64 (74.2-1ubuntu4) ... 193s Selecting previously unselected package postfix. 193s Preparing to unpack .../postfix_3.9.1-9_arm64.deb ... 193s Unpacking postfix (3.9.1-9) ... 193s Setting up libicu74:arm64 (74.2-1ubuntu4) ... 193s Setting up postfix (3.9.1-9) ... 194s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 194s info: Selecting UID from range 100 to 999 ... 194s 194s info: Selecting GID from range 100 to 999 ... 194s info: Adding system user `postfix' (UID 107) ... 194s info: Adding new group `postfix' (GID 111) ... 194s info: Adding new user `postfix' (UID 107) with group `postfix' ... 194s info: Not creating home directory `/var/spool/postfix'. 194s info: Selecting GID from range 100 to 999 ... 194s info: Adding group `postdrop' (GID 112) ... 194s Creating /etc/postfix/dynamicmaps.cf 194s setting myhostname: localhost 194s setting alias maps 194s setting alias database 194s mailname is not a fully qualified domain name. Not changing /etc/mailname. 194s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 194s setting relayhost: 194s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 194s setting mailbox_command 194s setting mailbox_size_limit: 0 194s setting recipient_delimiter: + 196s setting inet_interfaces: all 198s setting inet_protocols: all 198s /etc/aliases does not exist, creating it. 198s WARNING: /etc/aliases exists, but does not have a root alias. 198s 198s Postfix (main.cf) is now set up with a default configuration. If you need to 198s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 198s Postfix configuration values, see postconf(1). 198s 198s After modifying main.cf, be sure to run 'systemctl reload postfix'. 198s 200s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 200s Processing triggers for ufw (0.36.2-8) ... 201s Processing triggers for man-db (2.13.0-1) ... 202s Processing triggers for libc-bin (2.40-4ubuntu1) ... 202s Processing triggers for postfix (3.9.1-9) ... 202s Restarting postfix 207s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 281s ............... 281s ---------------------------------------------------------------------- 281s Ran 15 tests in 72.758s 281s 281s OK 281s ===== postfix.log contents: 281s Jan 13 23:53:21 localhost postfix/master[3238]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:53:22 localhost postfix/postfix-script[3486]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 281s Jan 13 23:53:25 localhost postfix/postfix-script[4242]: stopping the Postfix mail system 281s Jan 13 23:53:25 localhost postfix/master[3238]: terminating on signal 15 281s Jan 13 23:53:27 localhost postfix/master[4668]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: initializing the server-side TLS engine 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: initializing the server-side TLS engine 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: 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]> 281s Jan 13 23:53:29 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: B19B8438F1: client=localhost[127.0.0.1] 281s Jan 13 23:53:29 localhost postfix/smtpd[4673]: B19B8438F1: 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]> 281s Jan 13 23:53:29 localhost postfix/cleanup[4714]: B19B8438F1: message-id=<20250113235329.B19B8438F1@localhost> 281s Jan 13 23:53:29 localhost postfix/qmgr[4670]: B19B8438F1: from=, size=380, nrcpt=1 (queue active) 281s Jan 13 23:53:30 localhost postfix/local[4715]: B19B8438F1: 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") 281s Jan 13 23:53:30 localhost postfix/qmgr[4670]: B19B8438F1: removed 281s Jan 13 23:53:32 localhost postfix/smtpd[4673]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 281s Jan 13 23:53:32 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:34 localhost postfix/smtpd[4676]: warning: SASL authentication failure: Password verification failed 281s Jan 13 23:53:34 localhost postfix/smtpd[4676]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 281s Jan 13 23:53:36 localhost postfix/smtpd[4676]: warning: SASL authentication failure: Password verification failed 281s Jan 13 23:53:36 localhost postfix/smtpd[4676]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tkffyxer 281s Jan 13 23:53:36 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 281s Jan 13 23:53:36 localhost postfix/smtpd[4673]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:36 localhost postfix/smtpd[4673]: C4C7D438F1: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tmcyqjtj 281s Jan 13 23:53:36 localhost postfix/smtpd[4673]: C4C7D438F1: 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]> 281s Jan 13 23:53:36 localhost postfix/cleanup[4714]: C4C7D438F1: message-id=<20250113235336.C4C7D438F1@localhost> 281s Jan 13 23:53:36 localhost postfix/qmgr[4670]: C4C7D438F1: from=, size=416, nrcpt=1 (queue active) 281s Jan 13 23:53:37 localhost postfix/local[4715]: C4C7D438F1: 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") 281s Jan 13 23:53:37 localhost postfix/qmgr[4670]: C4C7D438F1: removed 281s Jan 13 23:53:39 localhost postfix/smtpd[4673]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: setting up TLS connection from localhost[127.0.0.1] 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:before SSL initialization 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:before SSL initialization 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS read client hello 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS write server hello 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS write change cipher spec 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:TLSv1.3 write encrypted extensions 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS write certificate 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:TLSv1.3 write server certificate verify 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS write finished 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:TLSv1.3 early data 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:TLSv1.3 early data 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS read finished 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736814219 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: SSL_accept:SSLv3/TLS write session ticket 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: 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 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: 1723F438F1: client=localhost[127.0.0.1] 281s Jan 13 23:53:40 localhost postfix/smtpd[4676]: 1723F438F1: 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]> 281s Jan 13 23:53:40 localhost postfix/cleanup[4714]: 1723F438F1: message-id=<20250113235340.1723F438F1@localhost> 281s Jan 13 23:53:40 localhost postfix/qmgr[4670]: 1723F438F1: from=, size=381, nrcpt=1 (queue active) 281s Jan 13 23:53:41 localhost postfix/local[4715]: 1723F438F1: 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") 281s Jan 13 23:53:41 localhost postfix/qmgr[4670]: 1723F438F1: removed 281s Jan 13 23:53:43 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 281s Jan 13 23:53:43 localhost postfix/smtpd[4673]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:43 localhost postfix/smtpd[4673]: 6749A438F7: client=localhost[127.0.0.1] 281s Jan 13 23:53:43 localhost postfix/smtpd[4673]: 6749A438F7: 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]> 281s Jan 13 23:53:43 localhost postfix/cleanup[4714]: 6749A438F7: message-id=<20250113235343.6749A438F7@localhost> 281s Jan 13 23:53:43 localhost postfix/qmgr[4670]: 6749A438F7: from=, size=380, nrcpt=1 (queue active) 281s Jan 13 23:53:43 localhost postfix/cleanup[4714]: 6A599438F8: message-id=<20250113235343.6749A438F7@localhost> 281s Jan 13 23:53:43 localhost postfix/local[4715]: 6749A438F7: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (forwarded as 6A599438F8) 281s Jan 13 23:53:43 localhost postfix/qmgr[4670]: 6A599438F8: from=, size=503, nrcpt=1 (queue active) 281s Jan 13 23:53:43 localhost postfix/qmgr[4670]: 6749A438F7: removed 281s Jan 13 23:53:44 localhost postfix/local[4715]: 6A599438F8: 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") 281s Jan 13 23:53:44 localhost postfix/qmgr[4670]: 6A599438F8: removed 281s Jan 13 23:53:46 localhost postfix/smtpd[4673]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 281s Jan 13 23:53:46 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:46 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:53:46 localhost postfix/smtpd[4673]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:47 localhost postfix/smtpd[4673]: 059E6438F1: client=localhost[127.0.0.1] 281s Jan 13 23:53:47 localhost postfix/cleanup[4714]: 059E6438F1: message-id=<20250113235347.059E6438F1@localhost> 281s Jan 13 23:53:47 localhost postfix/qmgr[4670]: 059E6438F1: from=, size=389, nrcpt=1 (queue active) 281s Jan 13 23:53:49 localhost postfix/smtpd[4673]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 281s Jan 13 23:53:49 localhost postfix/smtpd[4676]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:49 localhost postfix/smtpd[4676]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:53:53 localhost postfix/postfix-script[4894]: stopping the Postfix mail system 281s Jan 13 23:53:53 localhost postfix/master[4668]: terminating on signal 15 281s Jan 13 23:53:54 localhost postfix/master[5321]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:53:54 localhost postfix/qmgr[5323]: 059E6438F1: from=, size=389, nrcpt=1 (queue active) 281s Jan 13 23:53:54 localhost postfix/local[5327]: 059E6438F1: to=, orig_to=, relay=local, delay=7.7, delays=7.7/0.02/0/0.02, dsn=5.1.1, status=bounced (unknown user: "thaepkqc") 281s Jan 13 23:53:54 localhost postfix/cleanup[5329]: AFD54438F6: message-id=<20250113235354.AFD54438F6@localhost> 281s Jan 13 23:53:54 localhost postfix/bounce[5328]: 059E6438F1: sender non-delivery notification: AFD54438F6 281s Jan 13 23:53:54 localhost postfix/qmgr[5323]: AFD54438F6: from=<>, size=2190, nrcpt=1 (queue active) 281s Jan 13 23:53:54 localhost postfix/qmgr[5323]: 059E6438F1: removed 281s Jan 13 23:53:55 localhost postfix/local[5327]: AFD54438F6: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 281s Jan 13 23:53:55 localhost postfix/qmgr[5323]: AFD54438F6: removed 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: initializing the server-side TLS engine 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:56 localhost postfix/smtpd[5335]: initializing the server-side TLS engine 281s Jan 13 23:53:56 localhost postfix/smtpd[5335]: connect from localhost[127.0.0.1] 281s Jan 13 23:53:56 localhost postfix/smtpd[5335]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:53:56 localhost postfix/smtpd[5335]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: warning: SASL authentication failure: incorrect digest response 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tyaixtuk 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: D0A69438F1: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tyaixtuk 281s Jan 13 23:53:56 localhost postfix/smtpd[5332]: D0A69438F1: 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]> 281s Jan 13 23:53:56 localhost postfix/cleanup[5329]: D0A69438F1: message-id=<20250113235356.D0A69438F1@localhost> 281s Jan 13 23:53:56 localhost postfix/qmgr[5323]: D0A69438F1: from=, size=416, nrcpt=1 (queue active) 281s Jan 13 23:53:57 localhost postfix/local[5327]: D0A69438F1: 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") 281s Jan 13 23:53:57 localhost postfix/qmgr[5323]: D0A69438F1: removed 281s Jan 13 23:53:59 localhost postfix/smtpd[5332]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 281s Jan 13 23:54:00 localhost postfix/smtpd[5335]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:00 localhost postfix/smtpd[5335]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:54:04 localhost postfix/postfix-script[5373]: stopping the Postfix mail system 281s Jan 13 23:54:04 localhost postfix/master[5321]: terminating on signal 15 281s Jan 13 23:54:05 localhost postfix/master[5800]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:54:08 localhost postfix/smtpd[5805]: initializing the server-side TLS engine 281s Jan 13 23:54:08 localhost postfix/smtpd[5805]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: initializing the server-side TLS engine 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:54:08 localhost postfix/smtpd[5805]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:08 localhost postfix/smtpd[5808]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:54:12 localhost postfix/postfix-script[5844]: stopping the Postfix mail system 281s Jan 13 23:54:12 localhost postfix/master[5800]: terminating on signal 15 281s Jan 13 23:54:13 localhost postfix/master[6271]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:54:15 localhost postfix/smtpd[6276]: initializing the server-side TLS engine 281s Jan 13 23:54:15 localhost postfix/smtpd[6276]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:15 localhost postfix/smtpd[6279]: initializing the server-side TLS engine 281s Jan 13 23:54:15 localhost postfix/smtpd[6279]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:15 localhost postfix/smtpd[6279]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:54:15 localhost postfix/smtpd[6279]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:54:15 localhost postfix/smtpd[6276]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 281s Jan 13 23:54:16 localhost postfix/smtpd[6279]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:16 localhost postfix/smtpd[6279]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:54:20 localhost postfix/postfix-script[6315]: stopping the Postfix mail system 281s Jan 13 23:54:20 localhost postfix/master[6271]: terminating on signal 15 281s Jan 13 23:54:21 localhost postfix/master[6742]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:54:23 localhost postfix/smtpd[6747]: initializing the server-side TLS engine 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:24 localhost postfix/smtpd[6750]: initializing the server-side TLS engine 281s Jan 13 23:54:24 localhost postfix/smtpd[6750]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:24 localhost postfix/smtpd[6750]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:54:24 localhost postfix/smtpd[6750]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: warning: SASL authentication failure: Password verification failed 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: warning: SASL authentication failure: Password verification failed 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tqnzoptz 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: 0D8FD438F3: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tqnzoptz 281s Jan 13 23:54:24 localhost postfix/smtpd[6747]: 0D8FD438F3: 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]> 281s Jan 13 23:54:24 localhost postfix/cleanup[6752]: 0D8FD438F3: message-id=<20250113235424.0D8FD438F3@localhost> 281s Jan 13 23:54:24 localhost postfix/qmgr[6744]: 0D8FD438F3: from=, size=416, nrcpt=1 (queue active) 281s Jan 13 23:54:25 localhost postfix/local[6753]: 0D8FD438F3: to=, orig_to=, relay=local, delay=1, delays=0.02/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 281s Jan 13 23:54:25 localhost postfix/qmgr[6744]: 0D8FD438F3: removed 281s Jan 13 23:54:27 localhost postfix/smtpd[6747]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 281s Jan 13 23:54:27 localhost postfix/smtpd[6750]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:27 localhost postfix/smtpd[6750]: disconnect from localhost[127.0.0.1] quit=1 commands=1 281s Jan 13 23:54:31 localhost postfix/postfix-script[6791]: stopping the Postfix mail system 281s Jan 13 23:54:31 localhost postfix/master[6742]: terminating on signal 15 281s Jan 13 23:54:33 localhost postfix/master[7218]: daemon started -- version 3.9.1, configuration /etc/postfix 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: initializing the server-side TLS engine 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:35 localhost postfix/smtpd[7228]: initializing the server-side TLS engine 281s Jan 13 23:54:35 localhost postfix/smtpd[7228]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:35 localhost postfix/smtpd[7228]: lost connection after CONNECT from localhost[127.0.0.1] 281s Jan 13 23:54:35 localhost postfix/smtpd[7228]: disconnect from localhost[127.0.0.1] commands=0/0 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: warning: SASL authentication failure: Parse error 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: lost connection after AUTH from localhost[127.0.0.1] 281s Jan 13 23:54:35 localhost postfix/smtpd[7225]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 281s Jan 13 23:54:35 localhost postfix/smtpd[7228]: connect from localhost[127.0.0.1] 281s Jan 13 23:54:36 localhost postfix/smtpd[7228]: disconnect from localhost[127.0.0.1] quit=1 commands=1 282s autopkgtest [23:54:37]: test postfix: -----------------------] 282s postfix PASS 282s autopkgtest [23:54:37]: test postfix: - - - - - - - - - - results - - - - - - - - - - 283s autopkgtest [23:54:38]: @@@@@@@@@@@@@@@@@@@@ summary 283s postfix PASS 299s nova [W] Using flock in prodstack6-arm64 299s flock: timeout while waiting to get lock 299s Creating nova instance adt-plucky-arm64-postfix-20250113-234954-juju-7f2275-prod-proposed-migration-environment-20-07c1bfbf-0844-4bb2-a44e-ba0f14b8459d from image adt/ubuntu-plucky-arm64-server-20250113.img (UUID 8dd6e639-94bd-4e10-a42e-02c406dd662b)... 299s nova [W] Timed out waiting for 747ab3e1-e706-4490-a49a-693656479204 to get deleted.