0s autopkgtest [02:14:48]: starting date and time: 2025-01-07 02:14:48+0000 0s autopkgtest [02:14:48]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [02:14:48]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.uotochm1/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:mysql-8.4,src:icu,src:mysql-shell --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=mysql-8.4/8.4.3-0ubuntu3 icu/76.1-1ubuntu2 mysql-shell/8.0.38+dfsg-0ubuntu2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-27.secgroup --name adt-plucky-arm64-postfix-20250107-021446-juju-7f2275-prod-proposed-migration-environment-2-76ae2d4d-8f90-43b0-9d2f-9bc063e9722b --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 133s autopkgtest [02:17:01]: testbed dpkg architecture: arm64 134s autopkgtest [02:17:02]: testbed apt version: 2.9.18 134s autopkgtest [02:17:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 134s autopkgtest [02:17:02]: testbed release detected to be: None 135s autopkgtest [02:17:03]: updating testbed package index (apt update) 136s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 136s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 136s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 136s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 136s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [112 kB] 136s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.5 kB] 136s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [756 kB] 136s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 136s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [206 kB] 136s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [57.8 kB] 136s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [882 kB] 136s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [22.4 kB] 137s Fetched 2137 kB in 1s (2277 kB/s) 137s Reading package lists... 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 139s Calculating upgrade... 139s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 139s Reading package lists... 140s Building dependency tree... 140s Reading state information... 142s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s autopkgtest [02:17:10]: upgrading testbed (apt dist-upgrade and autopurge) 142s Reading package lists... 143s Building dependency tree... 143s Reading state information... 144s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 144s Starting 2 pkgProblemResolver with broken count: 0 144s Done 144s Entering ResolveByKeep 145s 145s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 145s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 147s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 151s autopkgtest [02:17:19]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 151s autopkgtest [02:17:19]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 159s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 159s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 159s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 159s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 159s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 159s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 159s gpgv: Can't check signature: No public key 159s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 160s autopkgtest [02:17:28]: testing package postfix version 3.9.1-9 161s autopkgtest [02:17:29]: build not needed 163s autopkgtest [02:17:31]: test postfix: preparing testbed 163s Reading package lists... 164s Building dependency tree... 164s Reading state information... 164s Starting pkgProblemResolver with broken count: 0 164s Starting 2 pkgProblemResolver with broken count: 0 164s Done 166s The following NEW packages will be installed: 166s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 166s ssl-cert 166s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 166s Need to get 399 kB of archives. 166s After this operation, 2545 kB of additional disk space will be used. 166s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 db5.3-util arm64 5.3.28+dfsg2-9 [72.1 kB] 166s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 db-util all 1:5.3.21ubuntu2 [2412 B] 166s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 sasl2-bin arm64 2.1.28+dfsg1-8 [95.0 kB] 166s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 procmail arm64 3.24-1ubuntu3 [147 kB] 166s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-ptyprocess all 0.7.0-6 [14.8 kB] 166s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-pexpect all 4.9-3 [48.1 kB] 166s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 ssl-cert all 1.1.3ubuntu1 [18.7 kB] 167s Preconfiguring packages ... 167s Fetched 399 kB in 1s (782 kB/s) 167s Selecting previously unselected package db5.3-util. 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 ... 80185 files and directories currently installed.) 167s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_arm64.deb ... 167s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 167s Selecting previously unselected package db-util. 167s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 167s Unpacking db-util (1:5.3.21ubuntu2) ... 167s Selecting previously unselected package sasl2-bin. 167s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_arm64.deb ... 167s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 167s Selecting previously unselected package procmail. 167s Preparing to unpack .../3-procmail_3.24-1ubuntu3_arm64.deb ... 167s Unpacking procmail (3.24-1ubuntu3) ... 168s Selecting previously unselected package python3-ptyprocess. 168s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 168s Unpacking python3-ptyprocess (0.7.0-6) ... 168s Selecting previously unselected package python3-pexpect. 168s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 168s Unpacking python3-pexpect (4.9-3) ... 168s Selecting previously unselected package ssl-cert. 168s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 168s Unpacking ssl-cert (1.1.3ubuntu1) ... 168s Setting up ssl-cert (1.1.3ubuntu1) ... 168s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 169s Setting up db5.3-util (5.3.28+dfsg2-9) ... 169s Setting up procmail (3.24-1ubuntu3) ... 169s Setting up python3-ptyprocess (0.7.0-6) ... 169s Setting up db-util (1:5.3.21ubuntu2) ... 169s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 170s saslauthd.service is a disabled or a static unit, not starting it. 170s Setting up python3-pexpect (4.9-3) ... 170s Processing triggers for man-db (2.13.0-1) ... 173s autopkgtest [02:17:41]: test postfix: [----------------------- 173s Reading package lists... 174s Building dependency tree... 174s Reading state information... 176s Suggested packages: 176s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 176s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 176s postfix-pgsql postfix-sqlite 176s The following NEW packages will be installed: 176s postfix 176s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 176s Need to get 1283 kB of archives. 176s After this operation, 5931 kB of additional disk space will be used. 176s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 postfix arm64 3.9.1-9 [1283 kB] 177s Preconfiguring packages ... 177s Fetched 1283 kB in 1s (2252 kB/s) 177s Selecting previously unselected package postfix. 177s (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 ... 80351 files and directories currently installed.) 177s Preparing to unpack .../postfix_3.9.1-9_arm64.deb ... 177s Unpacking postfix (3.9.1-9) ... 177s Setting up postfix (3.9.1-9) ... 178s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 179s info: Selecting UID from range 100 to 999 ... 179s 179s info: Selecting GID from range 100 to 999 ... 179s info: Adding system user `postfix' (UID 107) ... 179s info: Adding new group `postfix' (GID 111) ... 179s info: Adding new user `postfix' (UID 107) with group `postfix' ... 179s info: Not creating home directory `/var/spool/postfix'. 179s info: Selecting GID from range 100 to 999 ... 179s info: Adding group `postdrop' (GID 112) ... 179s Creating /etc/postfix/dynamicmaps.cf 179s setting myhostname: localhost 179s setting alias maps 179s setting alias database 179s mailname is not a fully qualified domain name. Not changing /etc/mailname. 179s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 179s setting relayhost: 179s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 179s setting mailbox_command 179s setting mailbox_size_limit: 0 179s setting recipient_delimiter: + 181s setting inet_interfaces: all 183s setting inet_protocols: all 183s /etc/aliases does not exist, creating it. 183s WARNING: /etc/aliases exists, but does not have a root alias. 183s 183s Postfix (main.cf) is now set up with a default configuration. If you need to 183s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 183s Postfix configuration values, see postconf(1). 183s 183s After modifying main.cf, be sure to run 'systemctl reload postfix'. 183s 185s Processing triggers for ufw (0.36.2-8) ... 185s Processing triggers for man-db (2.13.0-1) ... 186s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 186s Processing triggers for postfix (3.9.1-9) ... 186s Restarting postfix 192s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 264s ............... 264s ---------------------------------------------------------------------- 264s Ran 15 tests in 70.341s 264s 264s OK 264s ===== postfix.log contents: 264s Jan 07 02:17:59 localhost postfix/master[3252]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:00 localhost postfix/postfix-script[3500]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 264s Jan 07 02:18:03 localhost postfix/postfix-script[4256]: stopping the Postfix mail system 264s Jan 07 02:18:03 localhost postfix/master[3252]: terminating on signal 15 264s Jan 07 02:18:04 localhost postfix/master[4682]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:06 localhost postfix/smtpd[4687]: initializing the server-side TLS engine 264s Jan 07 02:18:06 localhost postfix/smtpd[4687]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: initializing the server-side TLS engine 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:06 localhost postfix/smtpd[4687]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:06 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:07 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:07 localhost postfix/smtpd[4690]: 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]> 264s Jan 07 02:18:07 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 264s Jan 07 02:18:07 localhost postfix/smtpd[4687]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:07 localhost postfix/smtpd[4687]: 5FF0243F2E: client=localhost[127.0.0.1] 264s Jan 07 02:18:07 localhost postfix/smtpd[4687]: 5FF0243F2E: 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]> 264s Jan 07 02:18:07 localhost postfix/cleanup[4728]: 5FF0243F2E: message-id=<20250107021807.5FF0243F2E@localhost> 264s Jan 07 02:18:07 localhost postfix/qmgr[4684]: 5FF0243F2E: from=, size=380, nrcpt=1 (queue active) 264s Jan 07 02:18:08 localhost postfix/local[4729]: 5FF0243F2E: 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") 264s Jan 07 02:18:08 localhost postfix/qmgr[4684]: 5FF0243F2E: removed 264s Jan 07 02:18:10 localhost postfix/smtpd[4687]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 264s Jan 07 02:18:10 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:13 localhost postfix/smtpd[4690]: warning: SASL authentication failure: Password verification failed 264s Jan 07 02:18:13 localhost postfix/smtpd[4690]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 264s Jan 07 02:18:15 localhost postfix/smtpd[4690]: warning: SASL authentication failure: Password verification failed 264s Jan 07 02:18:15 localhost postfix/smtpd[4690]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tbzmdcqd 264s Jan 07 02:18:15 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 264s Jan 07 02:18:15 localhost postfix/smtpd[4687]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:15 localhost postfix/smtpd[4687]: 2C93443F2E: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tnkhyetw 264s Jan 07 02:18:15 localhost postfix/smtpd[4687]: 2C93443F2E: 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]> 264s Jan 07 02:18:15 localhost postfix/cleanup[4728]: 2C93443F2E: message-id=<20250107021815.2C93443F2E@localhost> 264s Jan 07 02:18:15 localhost postfix/qmgr[4684]: 2C93443F2E: from=, size=416, nrcpt=1 (queue active) 264s Jan 07 02:18:16 localhost postfix/local[4729]: 2C93443F2E: 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") 264s Jan 07 02:18:16 localhost postfix/qmgr[4684]: 2C93443F2E: removed 264s Jan 07 02:18:18 localhost postfix/smtpd[4687]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: setting up TLS connection from localhost[127.0.0.1] 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:before SSL initialization 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:before SSL initialization 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS read client hello 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS write server hello 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS write change cipher spec 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:TLSv1.3 write encrypted extensions 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS write certificate 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:TLSv1.3 write server certificate verify 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS write finished 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:TLSv1.3 early data 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:TLSv1.3 early data 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS read finished 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736218097 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: SSL_accept:SSLv3/TLS write session ticket 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: 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 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: 6056343F2E: client=localhost[127.0.0.1] 264s Jan 07 02:18:18 localhost postfix/smtpd[4690]: 6056343F2E: 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]> 264s Jan 07 02:18:18 localhost postfix/cleanup[4728]: 6056343F2E: message-id=<20250107021818.6056343F2E@localhost> 264s Jan 07 02:18:18 localhost postfix/qmgr[4684]: 6056343F2E: from=, size=381, nrcpt=1 (queue active) 264s Jan 07 02:18:19 localhost postfix/local[4729]: 6056343F2E: 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") 264s Jan 07 02:18:19 localhost postfix/qmgr[4684]: 6056343F2E: removed 264s Jan 07 02:18:21 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 264s Jan 07 02:18:21 localhost postfix/smtpd[4687]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:21 localhost postfix/smtpd[4687]: AEC3B43F34: client=localhost[127.0.0.1] 264s Jan 07 02:18:21 localhost postfix/smtpd[4687]: AEC3B43F34: 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]> 264s Jan 07 02:18:21 localhost postfix/cleanup[4728]: AEC3B43F34: message-id=<20250107021821.AEC3B43F34@localhost> 264s Jan 07 02:18:21 localhost postfix/qmgr[4684]: AEC3B43F34: from=, size=380, nrcpt=1 (queue active) 264s Jan 07 02:18:21 localhost postfix/cleanup[4728]: B109443F35: message-id=<20250107021821.AEC3B43F34@localhost> 264s Jan 07 02:18:21 localhost postfix/local[4729]: AEC3B43F34: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0, dsn=2.0.0, status=sent (forwarded as B109443F35) 264s Jan 07 02:18:21 localhost postfix/qmgr[4684]: B109443F35: from=, size=503, nrcpt=1 (queue active) 264s Jan 07 02:18:21 localhost postfix/qmgr[4684]: AEC3B43F34: removed 264s Jan 07 02:18:22 localhost postfix/local[4729]: B109443F35: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 264s Jan 07 02:18:22 localhost postfix/qmgr[4684]: B109443F35: removed 264s Jan 07 02:18:24 localhost postfix/smtpd[4687]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 264s Jan 07 02:18:25 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:25 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:18:25 localhost postfix/smtpd[4687]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:25 localhost postfix/smtpd[4687]: 457C543F2E: client=localhost[127.0.0.1] 264s Jan 07 02:18:25 localhost postfix/cleanup[4728]: 457C543F2E: message-id=<20250107021825.457C543F2E@localhost> 264s Jan 07 02:18:25 localhost postfix/qmgr[4684]: 457C543F2E: from=, size=389, nrcpt=1 (queue active) 264s Jan 07 02:18:27 localhost postfix/smtpd[4687]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 264s Jan 07 02:18:27 localhost postfix/smtpd[4690]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:27 localhost postfix/smtpd[4690]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:18:31 localhost postfix/postfix-script[4907]: stopping the Postfix mail system 264s Jan 07 02:18:31 localhost postfix/master[4682]: terminating on signal 15 264s Jan 07 02:18:32 localhost postfix/master[5334]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:32 localhost postfix/qmgr[5336]: 457C543F2E: from=, size=389, nrcpt=1 (queue active) 264s Jan 07 02:18:32 localhost postfix/local[5340]: 457C543F2E: to=, orig_to=, relay=local, delay=7.2, delays=7.1/0.01/0/0.02, dsn=5.1.1, status=bounced (unknown user: "trbmokbl") 264s Jan 07 02:18:32 localhost postfix/cleanup[5342]: 7039943F33: message-id=<20250107021832.7039943F33@localhost> 264s Jan 07 02:18:32 localhost postfix/bounce[5341]: 457C543F2E: sender non-delivery notification: 7039943F33 264s Jan 07 02:18:32 localhost postfix/qmgr[5336]: 7039943F33: from=<>, size=2190, nrcpt=1 (queue active) 264s Jan 07 02:18:32 localhost postfix/qmgr[5336]: 457C543F2E: removed 264s Jan 07 02:18:33 localhost postfix/local[5340]: 7039943F33: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 264s Jan 07 02:18:33 localhost postfix/qmgr[5336]: 7039943F33: removed 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: initializing the server-side TLS engine 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:34 localhost postfix/smtpd[5349]: initializing the server-side TLS engine 264s Jan 07 02:18:34 localhost postfix/smtpd[5349]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:34 localhost postfix/smtpd[5349]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:34 localhost postfix/smtpd[5349]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: warning: SASL authentication failure: incorrect digest response 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tnejheyk 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: 8E86943F2E: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tnejheyk 264s Jan 07 02:18:34 localhost postfix/smtpd[5346]: 8E86943F2E: 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]> 264s Jan 07 02:18:34 localhost postfix/cleanup[5342]: 8E86943F2E: message-id=<20250107021834.8E86943F2E@localhost> 264s Jan 07 02:18:34 localhost postfix/qmgr[5336]: 8E86943F2E: from=, size=416, nrcpt=1 (queue active) 264s Jan 07 02:18:35 localhost postfix/local[5340]: 8E86943F2E: 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") 264s Jan 07 02:18:35 localhost postfix/qmgr[5336]: 8E86943F2E: removed 264s Jan 07 02:18:37 localhost postfix/smtpd[5346]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 264s Jan 07 02:18:37 localhost postfix/smtpd[5349]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:37 localhost postfix/smtpd[5349]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:18:41 localhost postfix/postfix-script[5387]: stopping the Postfix mail system 264s Jan 07 02:18:41 localhost postfix/master[5334]: terminating on signal 15 264s Jan 07 02:18:42 localhost postfix/master[5814]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:44 localhost postfix/smtpd[5819]: initializing the server-side TLS engine 264s Jan 07 02:18:44 localhost postfix/smtpd[5819]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: initializing the server-side TLS engine 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:44 localhost postfix/smtpd[5819]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:44 localhost postfix/smtpd[5822]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:18:48 localhost postfix/postfix-script[5858]: stopping the Postfix mail system 264s Jan 07 02:18:48 localhost postfix/master[5814]: terminating on signal 15 264s Jan 07 02:18:49 localhost postfix/master[6285]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:51 localhost postfix/smtpd[6290]: initializing the server-side TLS engine 264s Jan 07 02:18:51 localhost postfix/smtpd[6290]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: initializing the server-side TLS engine 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:51 localhost postfix/smtpd[6290]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:51 localhost postfix/smtpd[6293]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:18:55 localhost postfix/postfix-script[6329]: stopping the Postfix mail system 264s Jan 07 02:18:55 localhost postfix/master[6285]: terminating on signal 15 264s Jan 07 02:18:56 localhost postfix/master[6756]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: initializing the server-side TLS engine 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:59 localhost postfix/smtpd[6764]: initializing the server-side TLS engine 264s Jan 07 02:18:59 localhost postfix/smtpd[6764]: connect from localhost[127.0.0.1] 264s Jan 07 02:18:59 localhost postfix/smtpd[6764]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:18:59 localhost postfix/smtpd[6764]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: warning: SASL authentication failure: Password verification failed 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: warning: SASL authentication failure: Password verification failed 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=taumpmkw 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: 23DE643F30: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=taumpmkw 264s Jan 07 02:18:59 localhost postfix/smtpd[6761]: 23DE643F30: 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]> 264s Jan 07 02:18:59 localhost postfix/cleanup[6766]: 23DE643F30: message-id=<20250107021859.23DE643F30@localhost> 264s Jan 07 02:18:59 localhost postfix/qmgr[6758]: 23DE643F30: from=, size=416, nrcpt=1 (queue active) 264s Jan 07 02:19:00 localhost postfix/local[6767]: 23DE643F30: 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") 264s Jan 07 02:19:00 localhost postfix/qmgr[6758]: 23DE643F30: removed 264s Jan 07 02:19:02 localhost postfix/smtpd[6761]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 264s Jan 07 02:19:02 localhost postfix/smtpd[6764]: connect from localhost[127.0.0.1] 264s Jan 07 02:19:02 localhost postfix/smtpd[6764]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s Jan 07 02:19:06 localhost postfix/postfix-script[6805]: stopping the Postfix mail system 264s Jan 07 02:19:06 localhost postfix/master[6756]: terminating on signal 15 264s Jan 07 02:19:08 localhost postfix/master[7232]: daemon started -- version 3.9.1, configuration /etc/postfix 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: initializing the server-side TLS engine 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: connect from localhost[127.0.0.1] 264s Jan 07 02:19:10 localhost postfix/smtpd[7242]: initializing the server-side TLS engine 264s Jan 07 02:19:10 localhost postfix/smtpd[7242]: connect from localhost[127.0.0.1] 264s Jan 07 02:19:10 localhost postfix/smtpd[7242]: lost connection after CONNECT from localhost[127.0.0.1] 264s Jan 07 02:19:10 localhost postfix/smtpd[7242]: disconnect from localhost[127.0.0.1] commands=0/0 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: warning: SASL authentication failure: Parse error 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: lost connection after AUTH from localhost[127.0.0.1] 264s Jan 07 02:19:10 localhost postfix/smtpd[7239]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 264s Jan 07 02:19:10 localhost postfix/smtpd[7242]: connect from localhost[127.0.0.1] 264s Jan 07 02:19:11 localhost postfix/smtpd[7242]: disconnect from localhost[127.0.0.1] quit=1 commands=1 264s autopkgtest [02:19:12]: test postfix: -----------------------] 265s postfix PASS 265s autopkgtest [02:19:13]: test postfix: - - - - - - - - - - results - - - - - - - - - - 265s autopkgtest [02:19:13]: @@@@@@@@@@@@@@@@@@@@ summary 265s postfix PASS 284s nova [W] Using flock in prodstack6-arm64 284s flock: timeout while waiting to get lock 284s Creating nova instance adt-plucky-arm64-postfix-20250107-021446-juju-7f2275-prod-proposed-migration-environment-2-76ae2d4d-8f90-43b0-9d2f-9bc063e9722b from image adt/ubuntu-plucky-arm64-server-20250106.img (UUID 0be70afa-891d-4967-bd77-8481ebe681bd)... 284s nova [W] Timed out waiting for bce469dd-ec4f-48f6-a7f3-820c4d4d7d81 to get deleted.