0s autopkgtest [11:24:50]: starting date and time: 2025-01-06 11:24:50+0000 0s autopkgtest [11:24:50]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [11:24:50]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.elzy3ulr/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-17,src:icu --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=postgresql-17/17.2-1build2 icu/76.1-1ubuntu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-1.secgroup --name adt-plucky-ppc64el-postfix-20250106-112449-juju-7f2275-prod-proposed-migration-environment-15-74daa678-4d4b-4a7c-8493-e153cf6b216c --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 125s autopkgtest [11:26:55]: testbed dpkg architecture: ppc64el 125s autopkgtest [11:26:55]: testbed apt version: 2.9.18 125s autopkgtest [11:26:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 125s autopkgtest [11:26:55]: testbed release detected to be: None 126s autopkgtest [11:26:56]: updating testbed package index (apt update) 126s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 127s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 127s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 127s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 127s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 127s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [105 kB] 127s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [741 kB] 127s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.2 kB] 127s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [173 kB] 127s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 127s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [797 kB] 127s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [16.2 kB] 127s Fetched 1934 kB in 1s (1913 kB/s) 128s Reading package lists... 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Calculating upgrade... 129s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 130s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 130s autopkgtest [11:27:00]: upgrading testbed (apt dist-upgrade and autopurge) 130s Reading package lists... 130s Building dependency tree... 130s Reading state information... 130s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 130s Starting 2 pkgProblemResolver with broken count: 0 130s Done 131s Entering ResolveByKeep 131s 131s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 131s Reading package lists... 131s Building dependency tree... 131s Reading state information... 132s Starting pkgProblemResolver with broken count: 0 132s Starting 2 pkgProblemResolver with broken count: 0 132s Done 132s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s autopkgtest [11:27:05]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 135s autopkgtest [11:27:05]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 141s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 141s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 141s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 141s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 141s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 141s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 141s gpgv: Can't check signature: No public key 141s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 142s autopkgtest [11:27:12]: testing package postfix version 3.9.1-9 143s autopkgtest [11:27:13]: build not needed 144s autopkgtest [11:27:14]: test postfix: preparing testbed 145s Reading package lists... 145s Building dependency tree... 145s Reading state information... 145s Starting pkgProblemResolver with broken count: 0 145s Starting 2 pkgProblemResolver with broken count: 0 145s Done 145s The following NEW packages will be installed: 145s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 145s ssl-cert 145s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 145s Need to get 428 kB of archives. 145s After this operation, 2611 kB of additional disk space will be used. 145s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el db5.3-util ppc64el 5.3.28+dfsg2-9 [78.3 kB] 146s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el db-util all 1:5.3.21ubuntu2 [2412 B] 146s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el sasl2-bin ppc64el 2.1.28+dfsg1-8 [101 kB] 146s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el procmail ppc64el 3.24-1ubuntu3 [165 kB] 146s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-ptyprocess all 0.7.0-6 [14.8 kB] 146s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-pexpect all 4.9-3 [48.1 kB] 146s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el ssl-cert all 1.1.3ubuntu1 [18.7 kB] 146s Preconfiguring packages ... 146s Fetched 428 kB in 0s (887 kB/s) 146s Selecting previously unselected package db5.3-util. 146s (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 ... 74025 files and directories currently installed.) 146s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_ppc64el.deb ... 146s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 146s Selecting previously unselected package db-util. 146s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 146s Unpacking db-util (1:5.3.21ubuntu2) ... 146s Selecting previously unselected package sasl2-bin. 146s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_ppc64el.deb ... 146s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 146s Selecting previously unselected package procmail. 146s Preparing to unpack .../3-procmail_3.24-1ubuntu3_ppc64el.deb ... 146s Unpacking procmail (3.24-1ubuntu3) ... 146s Selecting previously unselected package python3-ptyprocess. 146s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 146s Unpacking python3-ptyprocess (0.7.0-6) ... 146s Selecting previously unselected package python3-pexpect. 146s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 146s Unpacking python3-pexpect (4.9-3) ... 146s Selecting previously unselected package ssl-cert. 146s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 146s Unpacking ssl-cert (1.1.3ubuntu1) ... 146s Setting up ssl-cert (1.1.3ubuntu1) ... 147s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 147s Setting up db5.3-util (5.3.28+dfsg2-9) ... 147s Setting up procmail (3.24-1ubuntu3) ... 147s Setting up python3-ptyprocess (0.7.0-6) ... 147s Setting up db-util (1:5.3.21ubuntu2) ... 147s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 148s saslauthd.service is a disabled or a static unit, not starting it. 148s Setting up python3-pexpect (4.9-3) ... 148s Processing triggers for man-db (2.13.0-1) ... 150s autopkgtest [11:27:20]: test postfix: [----------------------- 151s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s Suggested packages: 151s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 151s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 151s postfix-pgsql postfix-sqlite 151s The following NEW packages will be installed: 151s postfix 151s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 151s Need to get 1390 kB of archives. 151s After this operation, 6717 kB of additional disk space will be used. 151s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el postfix ppc64el 3.9.1-9 [1390 kB] 152s Preconfiguring packages ... 152s Fetched 1390 kB in 1s (2494 kB/s) 152s Selecting previously unselected package postfix. 152s (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 ... 74191 files and directories currently installed.) 152s Preparing to unpack .../postfix_3.9.1-9_ppc64el.deb ... 152s Unpacking postfix (3.9.1-9) ... 152s Setting up postfix (3.9.1-9) ... 153s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 153s info: Selecting UID from range 100 to 999 ... 153s 153s info: Selecting GID from range 100 to 999 ... 153s info: Adding system user `postfix' (UID 107) ... 153s info: Adding new group `postfix' (GID 111) ... 153s info: Adding new user `postfix' (UID 107) with group `postfix' ... 153s info: Not creating home directory `/var/spool/postfix'. 153s info: Selecting GID from range 100 to 999 ... 153s info: Adding group `postdrop' (GID 112) ... 153s Creating /etc/postfix/dynamicmaps.cf 153s setting myhostname: localhost 153s setting alias maps 153s setting alias database 153s mailname is not a fully qualified domain name. Not changing /etc/mailname. 153s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 153s setting relayhost: 153s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 153s setting mailbox_command 153s setting mailbox_size_limit: 0 153s setting recipient_delimiter: + 155s setting inet_interfaces: all 157s setting inet_protocols: all 157s /etc/aliases does not exist, creating it. 157s WARNING: /etc/aliases exists, but does not have a root alias. 157s 157s Postfix (main.cf) is now set up with a default configuration. If you need to 157s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 157s Postfix configuration values, see postconf(1). 157s 157s After modifying main.cf, be sure to run 'systemctl reload postfix'. 157s 159s Processing triggers for ufw (0.36.2-8) ... 159s Processing triggers for man-db (2.13.0-1) ... 160s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 160s Processing triggers for postfix (3.9.1-9) ... 160s Restarting postfix 164s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 232s ............... 232s ---------------------------------------------------------------------- 232s Ran 15 tests in 67.400s 232s 232s OK 232s ===== postfix.log contents: 232s Jan 06 11:27:33 localhost postfix/master[3328]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:27:34 localhost postfix/postfix-script[3576]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 232s Jan 06 11:27:37 localhost postfix/postfix-script[4332]: stopping the Postfix mail system 232s Jan 06 11:27:37 localhost postfix/master[3328]: terminating on signal 15 232s Jan 06 11:27:38 localhost postfix/master[4758]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: initializing the server-side TLS engine 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: initializing the server-side TLS engine 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: 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]> 232s Jan 06 11:27:40 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: 698BC3F10D: client=localhost[127.0.0.1] 232s Jan 06 11:27:40 localhost postfix/smtpd[4766]: 698BC3F10D: 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]> 232s Jan 06 11:27:40 localhost postfix/cleanup[4804]: 698BC3F10D: message-id=<20250106112740.698BC3F10D@localhost> 232s Jan 06 11:27:40 localhost postfix/qmgr[4760]: 698BC3F10D: from=, size=380, nrcpt=1 (queue active) 232s Jan 06 11:27:41 localhost postfix/local[4805]: 698BC3F10D: 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") 232s Jan 06 11:27:41 localhost postfix/qmgr[4760]: 698BC3F10D: removed 232s Jan 06 11:27:43 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 232s Jan 06 11:27:43 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:45 localhost postfix/smtpd[4763]: warning: SASL authentication failure: Password verification failed 232s Jan 06 11:27:45 localhost postfix/smtpd[4763]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 232s Jan 06 11:27:47 localhost postfix/smtpd[4763]: warning: SASL authentication failure: Password verification failed 232s Jan 06 11:27:47 localhost postfix/smtpd[4763]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tktpetks 232s Jan 06 11:27:47 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 232s Jan 06 11:27:47 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:47 localhost postfix/smtpd[4766]: B86DC3F10D: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tuaqrfsr 232s Jan 06 11:27:47 localhost postfix/smtpd[4766]: B86DC3F10D: 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]> 232s Jan 06 11:27:47 localhost postfix/cleanup[4804]: B86DC3F10D: message-id=<20250106112747.B86DC3F10D@localhost> 232s Jan 06 11:27:47 localhost postfix/qmgr[4760]: B86DC3F10D: from=, size=416, nrcpt=1 (queue active) 232s Jan 06 11:27:48 localhost postfix/local[4805]: B86DC3F10D: 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") 232s Jan 06 11:27:48 localhost postfix/qmgr[4760]: B86DC3F10D: removed 232s Jan 06 11:27:50 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: setting up TLS connection from localhost[127.0.0.1] 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:before SSL initialization 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:before SSL initialization 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS read client hello 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS write server hello 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS write change cipher spec 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:TLSv1.3 write encrypted extensions 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS write certificate 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:TLSv1.3 write server certificate verify 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS write finished 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:TLSv1.3 early data 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:TLSv1.3 early data 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS read finished 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736164669 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: SSL_accept:SSLv3/TLS write session ticket 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: 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 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: D66653F10D: client=localhost[127.0.0.1] 232s Jan 06 11:27:50 localhost postfix/smtpd[4763]: D66653F10D: 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]> 232s Jan 06 11:27:50 localhost postfix/cleanup[4804]: D66653F10D: message-id=<20250106112750.D66653F10D@localhost> 232s Jan 06 11:27:50 localhost postfix/qmgr[4760]: D66653F10D: from=, size=381, nrcpt=1 (queue active) 232s Jan 06 11:27:51 localhost postfix/local[4805]: D66653F10D: 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") 232s Jan 06 11:27:51 localhost postfix/qmgr[4760]: D66653F10D: removed 232s Jan 06 11:27:53 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 232s Jan 06 11:27:53 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:54 localhost postfix/smtpd[4766]: 0B20F3F113: client=localhost[127.0.0.1] 232s Jan 06 11:27:54 localhost postfix/smtpd[4766]: 0B20F3F113: 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]> 232s Jan 06 11:27:54 localhost postfix/cleanup[4804]: 0B20F3F113: message-id=<20250106112754.0B20F3F113@localhost> 232s Jan 06 11:27:54 localhost postfix/qmgr[4760]: 0B20F3F113: from=, size=380, nrcpt=1 (queue active) 232s Jan 06 11:27:54 localhost postfix/cleanup[4804]: 0C8243F114: message-id=<20250106112754.0B20F3F113@localhost> 232s Jan 06 11:27:54 localhost postfix/local[4805]: 0B20F3F113: to=, orig_to=, relay=local, delay=0.01, delays=0.01/0/0/0, dsn=2.0.0, status=sent (forwarded as 0C8243F114) 232s Jan 06 11:27:54 localhost postfix/qmgr[4760]: 0B20F3F113: removed 232s Jan 06 11:27:54 localhost postfix/qmgr[4760]: 0C8243F114: from=, size=503, nrcpt=1 (queue active) 232s Jan 06 11:27:55 localhost postfix/local[4805]: 0C8243F114: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Jan 06 11:27:55 localhost postfix/qmgr[4760]: 0C8243F114: removed 232s Jan 06 11:27:57 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 232s Jan 06 11:27:57 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:57 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:27:57 localhost postfix/smtpd[4766]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:57 localhost postfix/smtpd[4766]: 446CA3F10D: client=localhost[127.0.0.1] 232s Jan 06 11:27:57 localhost postfix/cleanup[4804]: 446CA3F10D: message-id=<20250106112757.446CA3F10D@localhost> 232s Jan 06 11:27:57 localhost postfix/qmgr[4760]: 446CA3F10D: from=, size=389, nrcpt=1 (queue active) 232s Jan 06 11:27:59 localhost postfix/smtpd[4766]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 232s Jan 06 11:27:59 localhost postfix/smtpd[4763]: connect from localhost[127.0.0.1] 232s Jan 06 11:27:59 localhost postfix/smtpd[4763]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:28:03 localhost postfix/postfix-script[4983]: stopping the Postfix mail system 232s Jan 06 11:28:03 localhost postfix/master[4758]: terminating on signal 15 232s Jan 06 11:28:04 localhost postfix/master[5410]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:28:04 localhost postfix/qmgr[5413]: 446CA3F10D: from=, size=389, nrcpt=1 (queue active) 232s Jan 06 11:28:04 localhost postfix/local[5416]: 446CA3F10D: to=, orig_to=, relay=local, delay=7.4, delays=7.4/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "tufhuibn") 232s Jan 06 11:28:04 localhost postfix/cleanup[5418]: AB5873F112: message-id=<20250106112804.AB5873F112@localhost> 232s Jan 06 11:28:04 localhost postfix/qmgr[5413]: AB5873F112: from=<>, size=2190, nrcpt=1 (queue active) 232s Jan 06 11:28:04 localhost postfix/bounce[5417]: 446CA3F10D: sender non-delivery notification: AB5873F112 232s Jan 06 11:28:04 localhost postfix/qmgr[5413]: 446CA3F10D: removed 232s Jan 06 11:28:05 localhost postfix/local[5416]: AB5873F112: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 232s Jan 06 11:28:05 localhost postfix/qmgr[5413]: AB5873F112: removed 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: initializing the server-side TLS engine 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:06 localhost postfix/smtpd[5424]: initializing the server-side TLS engine 232s Jan 06 11:28:06 localhost postfix/smtpd[5424]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:06 localhost postfix/smtpd[5424]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:28:06 localhost postfix/smtpd[5424]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: warning: SASL authentication failure: incorrect digest response 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=thtobyti 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: C71053F10D: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=thtobyti 232s Jan 06 11:28:06 localhost postfix/smtpd[5421]: C71053F10D: 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]> 232s Jan 06 11:28:06 localhost postfix/cleanup[5418]: C71053F10D: message-id=<20250106112806.C71053F10D@localhost> 232s Jan 06 11:28:06 localhost postfix/qmgr[5413]: C71053F10D: from=, size=416, nrcpt=1 (queue active) 232s Jan 06 11:28:07 localhost postfix/local[5416]: C71053F10D: 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") 232s Jan 06 11:28:07 localhost postfix/qmgr[5413]: C71053F10D: removed 232s Jan 06 11:28:09 localhost postfix/smtpd[5421]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 232s Jan 06 11:28:09 localhost postfix/smtpd[5424]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:09 localhost postfix/smtpd[5424]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:28:13 localhost postfix/postfix-script[5462]: stopping the Postfix mail system 232s Jan 06 11:28:13 localhost postfix/master[5410]: terminating on signal 15 232s Jan 06 11:28:14 localhost postfix/master[5889]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:28:16 localhost postfix/smtpd[5894]: initializing the server-side TLS engine 232s Jan 06 11:28:16 localhost postfix/smtpd[5894]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: initializing the server-side TLS engine 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:28:16 localhost postfix/smtpd[5894]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:16 localhost postfix/smtpd[5897]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:28:20 localhost postfix/postfix-script[5933]: stopping the Postfix mail system 232s Jan 06 11:28:20 localhost postfix/master[5889]: terminating on signal 15 232s Jan 06 11:28:21 localhost postfix/master[6360]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:28:23 localhost postfix/smtpd[6365]: initializing the server-side TLS engine 232s Jan 06 11:28:23 localhost postfix/smtpd[6365]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: initializing the server-side TLS engine 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:28:23 localhost postfix/smtpd[6365]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:23 localhost postfix/smtpd[6368]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:28:27 localhost postfix/postfix-script[6404]: stopping the Postfix mail system 232s Jan 06 11:28:27 localhost postfix/master[6360]: terminating on signal 15 232s Jan 06 11:28:28 localhost postfix/master[6831]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: initializing the server-side TLS engine 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:30 localhost postfix/smtpd[6839]: initializing the server-side TLS engine 232s Jan 06 11:28:30 localhost postfix/smtpd[6839]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:30 localhost postfix/smtpd[6839]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:28:30 localhost postfix/smtpd[6839]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: warning: SASL authentication failure: Password verification failed 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: warning: SASL authentication failure: Password verification failed 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tzveeyou 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: 9E7AB3F10F: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tzveeyou 232s Jan 06 11:28:30 localhost postfix/smtpd[6836]: 9E7AB3F10F: 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]> 232s Jan 06 11:28:30 localhost postfix/cleanup[6841]: 9E7AB3F10F: message-id=<20250106112830.9E7AB3F10F@localhost> 232s Jan 06 11:28:30 localhost postfix/qmgr[6833]: 9E7AB3F10F: from=, size=416, nrcpt=1 (queue active) 232s Jan 06 11:28:31 localhost postfix/local[6842]: 9E7AB3F10F: 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") 232s Jan 06 11:28:31 localhost postfix/qmgr[6833]: 9E7AB3F10F: removed 232s Jan 06 11:28:33 localhost postfix/smtpd[6836]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 232s Jan 06 11:28:33 localhost postfix/smtpd[6839]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:33 localhost postfix/smtpd[6839]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s Jan 06 11:28:37 localhost postfix/postfix-script[6880]: stopping the Postfix mail system 232s Jan 06 11:28:37 localhost postfix/master[6831]: terminating on signal 15 232s Jan 06 11:28:38 localhost postfix/master[7307]: daemon started -- version 3.9.1, configuration /etc/postfix 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: initializing the server-side TLS engine 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:40 localhost postfix/smtpd[7317]: initializing the server-side TLS engine 232s Jan 06 11:28:40 localhost postfix/smtpd[7317]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:40 localhost postfix/smtpd[7317]: lost connection after CONNECT from localhost[127.0.0.1] 232s Jan 06 11:28:40 localhost postfix/smtpd[7317]: disconnect from localhost[127.0.0.1] commands=0/0 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: warning: SASL authentication failure: Parse error 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: lost connection after AUTH from localhost[127.0.0.1] 232s Jan 06 11:28:40 localhost postfix/smtpd[7314]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 232s Jan 06 11:28:40 localhost postfix/smtpd[7317]: connect from localhost[127.0.0.1] 232s Jan 06 11:28:42 localhost postfix/smtpd[7317]: disconnect from localhost[127.0.0.1] quit=1 commands=1 232s autopkgtest [11:28:42]: test postfix: -----------------------] 233s postfix PASS 233s autopkgtest [11:28:43]: test postfix: - - - - - - - - - - results - - - - - - - - - - 233s autopkgtest [11:28:43]: @@@@@@@@@@@@@@@@@@@@ summary 233s postfix PASS 238s nova [W] Using flock in prodstack6-ppc64el 238s Creating nova instance adt-plucky-ppc64el-postfix-20250106-112449-juju-7f2275-prod-proposed-migration-environment-15-74daa678-4d4b-4a7c-8493-e153cf6b216c from image adt/ubuntu-plucky-ppc64el-server-20250106.img (UUID 66818982-bc25-4f66-80f3-4b4ddeb5bc55)... 238s nova [W] Timed out waiting for 2594c3c5-ec2b-4901-819a-63b97ac26ab7 to get deleted.