0s autopkgtest [09:58:04]: starting date and time: 2025-01-06 09:58:04+0000 0s autopkgtest [09:58:04]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [09:58:04]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ktosnrh4/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-s390x-9.secgroup --name adt-plucky-s390x-postfix-20250106-095804-juju-7f2275-prod-proposed-migration-environment-20-093da913-3b1d-460e-9567-ebed273950a6 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-s390x -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/ 58s autopkgtest [09:59:02]: testbed dpkg architecture: s390x 58s autopkgtest [09:59:02]: testbed apt version: 2.9.18 59s autopkgtest [09:59:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [09:59:03]: testbed release detected to be: None 59s autopkgtest [09:59:03]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 60s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 60s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 60s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 60s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 60s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.2 kB] 60s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [741 kB] 60s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [105 kB] 60s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [173 kB] 60s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 60s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [765 kB] 60s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [5928 B] 61s Fetched 1892 kB in 1s (2038 kB/s) 61s Reading package lists... 62s Reading package lists... 62s Building dependency tree... 62s Reading state information... 62s Calculating upgrade... 62s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s Reading package lists... 62s Building dependency tree... 62s Reading state information... 62s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s autopkgtest [09:59:06]: upgrading testbed (apt dist-upgrade and autopurge) 63s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 63s Starting 2 pkgProblemResolver with broken count: 0 63s Done 63s Entering ResolveByKeep 63s 63s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 64s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Starting pkgProblemResolver with broken count: 0 64s Starting 2 pkgProblemResolver with broken count: 0 64s Done 64s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s autopkgtest [09:59:10]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 66s autopkgtest [09:59:10]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 72s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (dsc) [3135 B] 72s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (tar) [4954 kB] 72s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (asc) [220 B] 72s Get:4 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-9 (diff) [200 kB] 72s gpgv: Signature made Tue Dec 24 18:30:19 2024 UTC 72s gpgv: using RSA key 64AA2AB531D56903366BFEF982AA4A243B1E9478 72s gpgv: Can't check signature: No public key 72s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-9.dsc: no acceptable signature found 72s autopkgtest [09:59:16]: testing package postfix version 3.9.1-9 73s autopkgtest [09:59:17]: build not needed 75s autopkgtest [09:59:19]: test postfix: preparing testbed 75s Reading package lists... 75s Building dependency tree... 75s Reading state information... 75s Starting pkgProblemResolver with broken count: 0 75s Starting 2 pkgProblemResolver with broken count: 0 75s Done 75s The following NEW packages will be installed: 75s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 75s ssl-cert 75s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 75s Need to get 414 kB of archives. 75s After this operation, 1336 kB of additional disk space will be used. 75s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x db5.3-util s390x 5.3.28+dfsg2-9 [75.3 kB] 76s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x db-util all 1:5.3.21ubuntu2 [2412 B] 76s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x sasl2-bin s390x 2.1.28+dfsg1-8 [97.2 kB] 76s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x procmail s390x 3.24-1ubuntu3 [157 kB] 76s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-ptyprocess all 0.7.0-6 [14.8 kB] 76s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pexpect all 4.9-3 [48.1 kB] 76s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 76s Preconfiguring packages ... 76s Fetched 414 kB in 0s (847 kB/s) 76s Selecting previously unselected package db5.3-util. 76s (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 ... 55616 files and directories currently installed.) 76s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_s390x.deb ... 76s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 76s Selecting previously unselected package db-util. 76s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 76s Unpacking db-util (1:5.3.21ubuntu2) ... 76s Selecting previously unselected package sasl2-bin. 76s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-8_s390x.deb ... 76s Unpacking sasl2-bin (2.1.28+dfsg1-8) ... 76s Selecting previously unselected package procmail. 76s Preparing to unpack .../3-procmail_3.24-1ubuntu3_s390x.deb ... 76s Unpacking procmail (3.24-1ubuntu3) ... 76s Selecting previously unselected package python3-ptyprocess. 76s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 76s Unpacking python3-ptyprocess (0.7.0-6) ... 76s Selecting previously unselected package python3-pexpect. 76s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 76s Unpacking python3-pexpect (4.9-3) ... 76s Selecting previously unselected package ssl-cert. 76s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 76s Unpacking ssl-cert (1.1.3ubuntu1) ... 76s Setting up ssl-cert (1.1.3ubuntu1) ... 77s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 77s Setting up db5.3-util (5.3.28+dfsg2-9) ... 77s Setting up procmail (3.24-1ubuntu3) ... 77s Setting up python3-ptyprocess (0.7.0-6) ... 77s Setting up db-util (1:5.3.21ubuntu2) ... 77s Setting up sasl2-bin (2.1.28+dfsg1-8) ... 78s saslauthd.service is a disabled or a static unit, not starting it. 78s Setting up python3-pexpect (4.9-3) ... 78s Processing triggers for man-db (2.13.0-1) ... 79s autopkgtest [09:59:23]: test postfix: [----------------------- 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 80s Suggested packages: 80s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 80s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 80s postfix-pgsql postfix-sqlite 80s The following NEW packages will be installed: 80s postfix 80s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 80s Need to get 1304 kB of archives. 80s After this operation, 4001 kB of additional disk space will be used. 80s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x postfix s390x 3.9.1-9 [1304 kB] 80s Preconfiguring packages ... 80s Fetched 1304 kB in 1s (2277 kB/s) 80s Selecting previously unselected package postfix. 81s (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 ... 55782 files and directories currently installed.) 81s Preparing to unpack .../postfix_3.9.1-9_s390x.deb ... 81s Unpacking postfix (3.9.1-9) ... 81s Setting up postfix (3.9.1-9) ... 81s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 81s info: Selecting UID from range 100 to 999 ... 81s 81s info: Selecting GID from range 100 to 999 ... 81s info: Adding system user `postfix' (UID 107) ... 81s info: Adding new group `postfix' (GID 111) ... 81s info: Adding new user `postfix' (UID 107) with group `postfix' ... 81s info: Not creating home directory `/var/spool/postfix'. 81s info: Selecting GID from range 100 to 999 ... 81s info: Adding group `postdrop' (GID 112) ... 81s Creating /etc/postfix/dynamicmaps.cf 81s setting myhostname: localhost 81s setting alias maps 81s setting alias database 81s mailname is not a fully qualified domain name. Not changing /etc/mailname. 81s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 81s setting relayhost: 81s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 81s setting mailbox_command 81s setting mailbox_size_limit: 0 81s setting recipient_delimiter: + 83s setting inet_interfaces: all 85s setting inet_protocols: all 85s /etc/aliases does not exist, creating it. 85s WARNING: /etc/aliases exists, but does not have a root alias. 85s 85s Postfix (main.cf) is now set up with a default configuration. If you need to 85s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 85s Postfix configuration values, see postconf(1). 85s 85s After modifying main.cf, be sure to run 'systemctl reload postfix'. 85s 87s Processing triggers for ufw (0.36.2-8) ... 87s Processing triggers for man-db (2.13.0-1) ... 88s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 88s Processing triggers for postfix (3.9.1-9) ... 88s Restarting postfix 91s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 157s ............... 157s ---------------------------------------------------------------------- 157s Ran 15 tests in 64.806s 157s 157s OK 157s ===== postfix.log contents: 157s Jan 06 09:59:35 localhost postfix/master[3197]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 09:59:35 localhost postfix/postfix-script[3445]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 157s Jan 06 09:59:38 localhost postfix/postfix-script[4201]: stopping the Postfix mail system 157s Jan 06 09:59:38 localhost postfix/master[3197]: terminating on signal 15 157s Jan 06 09:59:39 localhost postfix/master[4627]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: initializing the server-side TLS engine 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: initializing the server-side TLS engine 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: 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]> 157s Jan 06 09:59:41 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: 5DD107E008: client=localhost[127.0.0.1] 157s Jan 06 09:59:41 localhost postfix/smtpd[4632]: 5DD107E008: 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]> 157s Jan 06 09:59:41 localhost postfix/cleanup[4673]: 5DD107E008: message-id=<20250106095941.5DD107E008@localhost> 157s Jan 06 09:59:41 localhost postfix/qmgr[4629]: 5DD107E008: from=, size=380, nrcpt=1 (queue active) 157s Jan 06 09:59:42 localhost postfix/local[4674]: 5DD107E008: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 09:59:42 localhost postfix/qmgr[4629]: 5DD107E008: removed 157s Jan 06 09:59:44 localhost postfix/smtpd[4632]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 157s Jan 06 09:59:44 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:46 localhost postfix/smtpd[4635]: warning: SASL authentication failure: Password verification failed 157s Jan 06 09:59:46 localhost postfix/smtpd[4635]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 157s Jan 06 09:59:48 localhost postfix/smtpd[4635]: warning: SASL authentication failure: Password verification failed 157s Jan 06 09:59:48 localhost postfix/smtpd[4635]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tycocsyt 157s Jan 06 09:59:48 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 157s Jan 06 09:59:48 localhost postfix/smtpd[4632]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:48 localhost postfix/smtpd[4632]: 792B27E008: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=thjfuhia 157s Jan 06 09:59:48 localhost postfix/smtpd[4632]: 792B27E008: 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]> 157s Jan 06 09:59:48 localhost postfix/cleanup[4673]: 792B27E008: message-id=<20250106095948.792B27E008@localhost> 157s Jan 06 09:59:48 localhost postfix/qmgr[4629]: 792B27E008: from=, size=416, nrcpt=1 (queue active) 157s Jan 06 09:59:49 localhost postfix/local[4674]: 792B27E008: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 09:59:49 localhost postfix/qmgr[4629]: 792B27E008: removed 157s Jan 06 09:59:51 localhost postfix/smtpd[4632]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: setting up TLS connection from localhost[127.0.0.1] 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:before SSL initialization 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:before SSL initialization 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS read client hello 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS write server hello 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS write change cipher spec 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:TLSv1.3 write encrypted extensions 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS write certificate 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:TLSv1.3 write server certificate verify 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS write finished 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:TLSv1.3 early data 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:TLSv1.3 early data 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS read finished 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1736159390 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: SSL_accept:SSLv3/TLS write session ticket 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: 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 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: 8849D7E008: client=localhost[127.0.0.1] 157s Jan 06 09:59:51 localhost postfix/smtpd[4635]: 8849D7E008: 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]> 157s Jan 06 09:59:51 localhost postfix/cleanup[4673]: 8849D7E008: message-id=<20250106095951.8849D7E008@localhost> 157s Jan 06 09:59:51 localhost postfix/qmgr[4629]: 8849D7E008: from=, size=381, nrcpt=1 (queue active) 157s Jan 06 09:59:52 localhost postfix/local[4674]: 8849D7E008: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 09:59:52 localhost postfix/qmgr[4629]: 8849D7E008: removed 157s Jan 06 09:59:54 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 157s Jan 06 09:59:54 localhost postfix/smtpd[4632]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:54 localhost postfix/smtpd[4632]: 98B887E00E: client=localhost[127.0.0.1] 157s Jan 06 09:59:54 localhost postfix/smtpd[4632]: 98B887E00E: 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]> 157s Jan 06 09:59:54 localhost postfix/cleanup[4673]: 98B887E00E: message-id=<20250106095954.98B887E00E@localhost> 157s Jan 06 09:59:54 localhost postfix/qmgr[4629]: 98B887E00E: from=, size=380, nrcpt=1 (queue active) 157s Jan 06 09:59:54 localhost postfix/cleanup[4673]: 992327E00F: message-id=<20250106095954.98B887E00E@localhost> 157s Jan 06 09:59:54 localhost postfix/local[4674]: 98B887E00E: to=, orig_to=, relay=local, delay=0, delays=0/0/0/0, dsn=2.0.0, status=sent (forwarded as 992327E00F) 157s Jan 06 09:59:54 localhost postfix/qmgr[4629]: 992327E00F: from=, size=503, nrcpt=1 (queue active) 157s Jan 06 09:59:54 localhost postfix/qmgr[4629]: 98B887E00E: removed 157s Jan 06 09:59:55 localhost postfix/local[4674]: 992327E00F: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 09:59:55 localhost postfix/qmgr[4629]: 992327E00F: removed 157s Jan 06 09:59:57 localhost postfix/smtpd[4632]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 157s Jan 06 09:59:57 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:57 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 09:59:57 localhost postfix/smtpd[4632]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:57 localhost postfix/smtpd[4632]: B827C7E008: client=localhost[127.0.0.1] 157s Jan 06 09:59:57 localhost postfix/cleanup[4673]: B827C7E008: message-id=<20250106095957.B827C7E008@localhost> 157s Jan 06 09:59:57 localhost postfix/qmgr[4629]: B827C7E008: from=, size=389, nrcpt=1 (queue active) 157s Jan 06 09:59:59 localhost postfix/smtpd[4632]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 157s Jan 06 09:59:59 localhost postfix/smtpd[4635]: connect from localhost[127.0.0.1] 157s Jan 06 09:59:59 localhost postfix/smtpd[4635]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 10:00:03 localhost postfix/postfix-script[4852]: stopping the Postfix mail system 157s Jan 06 10:00:03 localhost postfix/master[4627]: terminating on signal 15 157s Jan 06 10:00:03 localhost postfix/master[5279]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 10:00:03 localhost postfix/qmgr[5281]: B827C7E008: from=, size=389, nrcpt=1 (queue active) 157s Jan 06 10:00:03 localhost postfix/local[5285]: B827C7E008: to=, orig_to=, relay=local, delay=6.1, delays=6.1/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "ttinqkpd") 157s Jan 06 10:00:03 localhost postfix/cleanup[5287]: CE0F87E00D: message-id=<20250106100003.CE0F87E00D@localhost> 157s Jan 06 10:00:03 localhost postfix/bounce[5286]: B827C7E008: sender non-delivery notification: CE0F87E00D 157s Jan 06 10:00:03 localhost postfix/qmgr[5281]: CE0F87E00D: from=<>, size=2190, nrcpt=1 (queue active) 157s Jan 06 10:00:03 localhost postfix/qmgr[5281]: B827C7E008: removed 157s Jan 06 10:00:04 localhost postfix/local[5285]: CE0F87E00D: to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 10:00:04 localhost postfix/qmgr[5281]: CE0F87E00D: removed 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: initializing the server-side TLS engine 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:05 localhost postfix/smtpd[5293]: initializing the server-side TLS engine 157s Jan 06 10:00:05 localhost postfix/smtpd[5293]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:05 localhost postfix/smtpd[5293]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 10:00:05 localhost postfix/smtpd[5293]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: warning: SASL authentication failure: incorrect digest response 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tsdxxewi 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: EA4647E008: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tsdxxewi 157s Jan 06 10:00:05 localhost postfix/smtpd[5290]: EA4647E008: 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]> 157s Jan 06 10:00:05 localhost postfix/cleanup[5287]: EA4647E008: message-id=<20250106100005.EA4647E008@localhost> 157s Jan 06 10:00:05 localhost postfix/qmgr[5281]: EA4647E008: from=, size=416, nrcpt=1 (queue active) 157s Jan 06 10:00:06 localhost postfix/local[5285]: EA4647E008: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 10:00:06 localhost postfix/qmgr[5281]: EA4647E008: removed 157s Jan 06 10:00:08 localhost postfix/smtpd[5290]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 157s Jan 06 10:00:08 localhost postfix/smtpd[5293]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:08 localhost postfix/smtpd[5293]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 10:00:12 localhost postfix/postfix-script[5331]: stopping the Postfix mail system 157s Jan 06 10:00:12 localhost postfix/master[5279]: terminating on signal 15 157s Jan 06 10:00:13 localhost postfix/master[5758]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 10:00:15 localhost postfix/smtpd[5763]: initializing the server-side TLS engine 157s Jan 06 10:00:15 localhost postfix/smtpd[5763]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: initializing the server-side TLS engine 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 10:00:15 localhost postfix/smtpd[5763]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:15 localhost postfix/smtpd[5766]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 10:00:19 localhost postfix/postfix-script[5802]: stopping the Postfix mail system 157s Jan 06 10:00:19 localhost postfix/master[5758]: terminating on signal 15 157s Jan 06 10:00:20 localhost postfix/master[6232]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 10:00:22 localhost postfix/smtpd[6237]: initializing the server-side TLS engine 157s Jan 06 10:00:22 localhost postfix/smtpd[6237]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:22 localhost postfix/smtpd[6240]: initializing the server-side TLS engine 157s Jan 06 10:00:22 localhost postfix/smtpd[6240]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:22 localhost postfix/smtpd[6240]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 10:00:22 localhost postfix/smtpd[6240]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 10:00:22 localhost postfix/smtpd[6237]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 157s Jan 06 10:00:23 localhost postfix/smtpd[6240]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:23 localhost postfix/smtpd[6240]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 10:00:27 localhost postfix/postfix-script[6276]: stopping the Postfix mail system 157s Jan 06 10:00:27 localhost postfix/master[6232]: terminating on signal 15 157s Jan 06 10:00:27 localhost postfix/master[6703]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: initializing the server-side TLS engine 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:30 localhost postfix/smtpd[6711]: initializing the server-side TLS engine 157s Jan 06 10:00:30 localhost postfix/smtpd[6711]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:30 localhost postfix/smtpd[6711]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 10:00:30 localhost postfix/smtpd[6711]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: warning: SASL authentication failure: Password verification failed 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: warning: SASL authentication failure: Password verification failed 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=teylnquq 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: 14FBE7E00A: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=teylnquq 157s Jan 06 10:00:30 localhost postfix/smtpd[6708]: 14FBE7E00A: 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]> 157s Jan 06 10:00:30 localhost postfix/cleanup[6713]: 14FBE7E00A: message-id=<20250106100030.14FBE7E00A@localhost> 157s Jan 06 10:00:30 localhost postfix/qmgr[6705]: 14FBE7E00A: from=, size=416, nrcpt=1 (queue active) 157s Jan 06 10:00:31 localhost postfix/local[6714]: 14FBE7E00A: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 157s Jan 06 10:00:31 localhost postfix/qmgr[6705]: 14FBE7E00A: removed 157s Jan 06 10:00:33 localhost postfix/smtpd[6708]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 157s Jan 06 10:00:33 localhost postfix/smtpd[6711]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:33 localhost postfix/smtpd[6711]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s Jan 06 10:00:37 localhost postfix/postfix-script[6752]: stopping the Postfix mail system 157s Jan 06 10:00:37 localhost postfix/master[6703]: terminating on signal 15 157s Jan 06 10:00:38 localhost postfix/master[7179]: daemon started -- version 3.9.1, configuration /etc/postfix 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: initializing the server-side TLS engine 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:40 localhost postfix/smtpd[7189]: initializing the server-side TLS engine 157s Jan 06 10:00:40 localhost postfix/smtpd[7189]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:40 localhost postfix/smtpd[7189]: lost connection after CONNECT from localhost[127.0.0.1] 157s Jan 06 10:00:40 localhost postfix/smtpd[7189]: disconnect from localhost[127.0.0.1] commands=0/0 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: warning: SASL authentication failure: Parse error 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: lost connection after AUTH from localhost[127.0.0.1] 157s Jan 06 10:00:40 localhost postfix/smtpd[7186]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 157s Jan 06 10:00:40 localhost postfix/smtpd[7189]: connect from localhost[127.0.0.1] 157s Jan 06 10:00:41 localhost postfix/smtpd[7189]: disconnect from localhost[127.0.0.1] quit=1 commands=1 157s autopkgtest [10:00:41]: test postfix: -----------------------] 158s autopkgtest [10:00:42]: test postfix: - - - - - - - - - - results - - - - - - - - - - 158s postfix PASS 158s autopkgtest [10:00:42]: @@@@@@@@@@@@@@@@@@@@ summary 158s postfix PASS 175s nova [W] Using flock in prodstack6-s390x 175s Creating nova instance adt-plucky-s390x-postfix-20250106-095804-juju-7f2275-prod-proposed-migration-environment-20-093da913-3b1d-460e-9567-ebed273950a6 from image adt/ubuntu-plucky-s390x-server-20250106.img (UUID 5b452c36-3901-4afa-abc7-e21db541e94d)... 175s nova [W] Timed out waiting for 4f646035-ba11-493a-be73-7f00b33b5202 to get deleted.