0s autopkgtest [20:09:28]: starting date and time: 2025-03-15 20:09:28+0000 0s autopkgtest [20:09:28]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [20:09:28]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.v9ivv51_/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade postfix --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-riscv64-8.secgroup --name adt-plucky-riscv64-postfix-20250315-200928-juju-7f2275-prod-proposed-migration-environment-15-b7669d10-1696-46c8-8ff9-af57e9e9ba2c --image adt/ubuntu-plucky-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-riscv64 -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/ 304s autopkgtest [20:14:32]: testbed dpkg architecture: riscv64 306s autopkgtest [20:14:34]: testbed apt version: 2.9.33 308s autopkgtest [20:14:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup 310s autopkgtest [20:14:38]: testbed release detected to be: None 316s autopkgtest [20:14:44]: updating testbed package index (apt update) 319s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 320s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 320s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 320s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 321s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 322s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [45.1 kB] 322s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 322s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 Packages [73.5 kB] 322s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 c-n-f Metadata [1752 B] 322s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted riscv64 c-n-f Metadata [120 B] 323s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 Packages [306 kB] 323s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 c-n-f Metadata [14.6 kB] 323s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 Packages [3532 B] 323s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 c-n-f Metadata [272 B] 334s Fetched 955 kB in 6s (157 kB/s) 355s Reading package lists... 360s + lsb_release --codename --short 360s + RELEASE=plucky 360s + cat 360s + [ plucky != trusty ] 360s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 361s Reading package lists... 363s Building dependency tree... 364s Reading state information... 366s Calculating upgrade... 367s Calculating upgrade... 369s The following packages will be upgraded: 369s pinentry-curses python3-jinja2 strace 369s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 369s Need to get 615 kB of archives. 369s After this operation, 27.6 kB of additional disk space will be used. 369s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 strace riscv64 6.13+ds-1ubuntu1 [460 kB] 370s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 pinentry-curses riscv64 1.3.1-2ubuntu3 [45.4 kB] 370s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 377s Fetched 615 kB in 1s (610 kB/s) 379s (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 ... 114110 files and directories currently installed.) 379s Preparing to unpack .../strace_6.13+ds-1ubuntu1_riscv64.deb ... 379s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 381s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_riscv64.deb ... 381s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 381s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 383s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 384s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 384s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 389s Setting up strace (6.13+ds-1ubuntu1) ... 389s Processing triggers for man-db (2.13.0-1) ... 402s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 402s + /usr/lib/apt/apt-helper analyze-pattern ?true 403s + uname -r 403s + sed s/\./\\./g 403s + running_kernel_pattern=^linux-.*6\.14\.0-7-generic.* 403s + apt list ?obsolete 403s + + cut -d/ -f1 403s tail -n+2 403s + grep -v ^linux-.*6\.14\.0-7-generic.* 406s + obsolete_pkgs=linux-headers-6.11.0-8-generic 406s linux-image-6.11.0-8-generic 406s linux-modules-6.11.0-8-generic 406s linux-riscv-headers-6.11.0-8 406s linux-riscv-tools-6.11.0-8 406s linux-tools-6.11.0-8-generic 406s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-image-6.11.0-8-generic linux-modules-6.11.0-8-generic linux-riscv-headers-6.11.0-8 linux-riscv-tools-6.11.0-8 linux-tools-6.11.0-8-generic 407s Reading package lists... 409s Building dependency tree... 409s Reading state information... 413s Solving dependencies... 415s The following packages will be REMOVED: 415s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 415s linux-headers-6.11.0-8-generic* linux-image-6.11.0-8-generic* 415s linux-modules-6.11.0-8-generic* linux-riscv-headers-6.11.0-8* 415s linux-riscv-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 421s 0 upgraded, 0 newly installed, 10 to remove and 5 not upgraded. 421s After this operation, 355 MB disk space will be freed. 424s (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 ... 114110 files and directories currently installed.) 424s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8.1) ... 424s Removing linux-riscv-tools-6.11.0-8 (6.11.0-8.8.1) ... 424s Removing libpython3.12t64:riscv64 (3.12.9-1) ... 424s Removing libpython3.12-stdlib:riscv64 (3.12.9-1) ... 427s Removing libnsl2:riscv64 (1.3.0-3build3) ... 427s Removing libpython3.12-minimal:riscv64 (3.12.9-1) ... 430s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8.1) ... 437s Removing linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 442s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-7-generic 442s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-7-generic 443s /etc/kernel/postrm.d/initramfs-tools: 443s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 444s /etc/kernel/postrm.d/zz-u-boot-menu: 444s P: Checking for EXTLINUX directory... found. 445s P: Writing config for vmlinuz-6.14.0-7-generic... 445s P: Updating /boot/extlinux/extlinux.conf... 448s /etc/kernel/postrm.d/zz-update-grub: 448s Sourcing file `/etc/default/grub' 448s Generating grub configuration file ... 455s Found linux image: /boot/vmlinuz-6.14.0-7-generic 455s Found initrd image: /boot/initrd.img-6.14.0-7-generic 463s Warning: os-prober will not be executed to detect other bootable partitions. 463s Systems on them will not be added to the GRUB boot configuration. 463s Check GRUB_DISABLE_OS_PROBER documentation entry. 463s Adding boot menu entry for UEFI Firmware Settings ... 464s done 465s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 472s Removing linux-riscv-headers-6.11.0-8 (6.11.0-8.8.1) ... 491s Processing triggers for libc-bin (2.41-1ubuntu1) ... 493s (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 ... 75587 files and directories currently installed.) 493s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 495s /etc/kernel/postrm.d/zz-u-boot-menu: 495s P: Checking for EXTLINUX directory... found. 497s P: Writing config for vmlinuz-6.14.0-7-generic... 499s Purging configuration files for libpython3.12-minimal:riscv64 (3.12.9-1) ... 499s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 508s + grep -q trusty /etc/lsb-release 508s + [ ! -d /usr/share/doc/unattended-upgrades ] 508s + [ ! -d /usr/share/doc/lxd ] 508s + [ ! -d /usr/share/doc/lxd-client ] 508s + [ ! -d /usr/share/doc/snapd ] 508s + type iptables 508s + cat 508s + chmod 755 /etc/rc.local 508s + . /etc/rc.local 508s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 508s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 508s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 508s + uname -m 508s + [ riscv64 = ppc64le ] 508s + [ -d /run/systemd/system ] 508s + systemd-detect-virt --quiet --vm 508s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 508s + cat 508s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 508s + echo COMPRESS=lz4 509s autopkgtest [20:17:57]: upgrading testbed (apt dist-upgrade and autopurge) 511s Reading package lists... 513s Building dependency tree... 513s Reading state information... 517s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 518s Starting 2 pkgProblemResolver with broken count: 0 518s Done 523s Entering ResolveByKeep 525s 526s Calculating upgrade... 527s The following packages will be upgraded: 527s libc-bin libc-dev-bin libc6 libc6-dev locales 528s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 528s Need to get 11.3 MB of archives. 528s After this operation, 2048 B of additional disk space will be used. 528s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6-dev riscv64 2.41-1ubuntu2 [3560 kB] 530s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-dev-bin riscv64 2.41-1ubuntu2 [24.0 kB] 530s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6 riscv64 2.41-1ubuntu2 [2845 kB] 532s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-bin riscv64 2.41-1ubuntu2 [640 kB] 533s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 locales all 2.41-1ubuntu2 [4246 kB] 542s Preconfiguring packages ... 545s Fetched 11.3 MB in 8s (1434 kB/s) 547s (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 ... 75584 files and directories currently installed.) 547s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_riscv64.deb ... 547s Unpacking libc6-dev:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 554s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_riscv64.deb ... 554s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 555s Preparing to unpack .../libc6_2.41-1ubuntu2_riscv64.deb ... 560s Unpacking libc6:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 563s Setting up libc6:riscv64 (2.41-1ubuntu2) ... 569s (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 ... 75584 files and directories currently installed.) 569s Preparing to unpack .../libc-bin_2.41-1ubuntu2_riscv64.deb ... 569s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 570s Setting up libc-bin (2.41-1ubuntu2) ... 572s (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 ... 75584 files and directories currently installed.) 572s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 572s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 577s Setting up locales (2.41-1ubuntu2) ... 617s Generating locales (this might take a while)... 639s en_US.UTF-8... done 639s Generation complete. 639s Setting up libc-dev-bin (2.41-1ubuntu2) ... 639s Setting up libc6-dev:riscv64 (2.41-1ubuntu2) ... 639s Processing triggers for man-db (2.13.0-1) ... 645s Processing triggers for systemd (257.3-1ubuntu3) ... 665s Reading package lists... 667s Building dependency tree... 667s Reading state information... 670s Starting pkgProblemResolver with broken count: 0 671s Starting 2 pkgProblemResolver with broken count: 0 671s Done 675s Solving dependencies... 676s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 678s autopkgtest [20:20:46]: rebooting testbed after setup commands that affected boot 688s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 721s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 754s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 787s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 820s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 840s autopkgtest [20:23:28]: testbed running kernel: Linux 6.14.0-7-generic #7.1-Ubuntu SMP PREEMPT_DYNAMIC Sat Mar 1 14:36:03 UTC 2025 857s autopkgtest [20:23:45]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 919s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (dsc) [3002 B] 919s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (tar) [4954 kB] 919s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (diff) [202 kB] 923s gpgv: Signature made Fri Jan 31 12:46:11 2025 UTC 923s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 923s gpgv: Can't check signature: No public key 923s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-10ubuntu1.dsc: no acceptable signature found 931s autopkgtest [20:24:59]: testing package postfix version 3.9.1-10ubuntu1 935s autopkgtest [20:25:03]: build not needed 940s autopkgtest [20:25:08]: test postfix: preparing testbed 945s Reading package lists... 947s Building dependency tree... 947s Reading state information... 950s Starting pkgProblemResolver with broken count: 0 951s Starting 2 pkgProblemResolver with broken count: 0 951s Done 955s The following NEW packages will be installed: 955s db-util db5.3-util procmail python3-pexpect python3-ptyprocess sasl2-bin 955s ssl-cert 956s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 956s Need to get 414 kB of archives. 956s After this operation, 1307 kB of additional disk space will be used. 956s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 db5.3-util riscv64 5.3.28+dfsg2-9 [77.4 kB] 957s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 db-util all 1:5.3.21ubuntu2 [2412 B] 957s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 sasl2-bin riscv64 2.1.28+dfsg1-9 [103 kB] 957s Get:4 http://ftpmaster.internal/ubuntu plucky/main riscv64 procmail riscv64 3.24-1ubuntu3 [149 kB] 957s Get:5 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-ptyprocess all 0.7.0-6 [14.8 kB] 957s Get:6 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-pexpect all 4.9-3 [48.1 kB] 957s Get:7 http://ftpmaster.internal/ubuntu plucky/main riscv64 ssl-cert all 1.1.3ubuntu1 [18.7 kB] 963s Preconfiguring packages ... 964s Fetched 414 kB in 1s (329 kB/s) 965s Selecting previously unselected package db5.3-util. 966s (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 ... 75584 files and directories currently installed.) 966s Preparing to unpack .../0-db5.3-util_5.3.28+dfsg2-9_riscv64.deb ... 966s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 967s Selecting previously unselected package db-util. 967s Preparing to unpack .../1-db-util_1%3a5.3.21ubuntu2_all.deb ... 967s Unpacking db-util (1:5.3.21ubuntu2) ... 968s Selecting previously unselected package sasl2-bin. 968s Preparing to unpack .../2-sasl2-bin_2.1.28+dfsg1-9_riscv64.deb ... 968s Unpacking sasl2-bin (2.1.28+dfsg1-9) ... 969s Selecting previously unselected package procmail. 969s Preparing to unpack .../3-procmail_3.24-1ubuntu3_riscv64.deb ... 969s Unpacking procmail (3.24-1ubuntu3) ... 970s Selecting previously unselected package python3-ptyprocess. 970s Preparing to unpack .../4-python3-ptyprocess_0.7.0-6_all.deb ... 970s Unpacking python3-ptyprocess (0.7.0-6) ... 971s Selecting previously unselected package python3-pexpect. 971s Preparing to unpack .../5-python3-pexpect_4.9-3_all.deb ... 971s Unpacking python3-pexpect (4.9-3) ... 971s Selecting previously unselected package ssl-cert. 971s Preparing to unpack .../6-ssl-cert_1.1.3ubuntu1_all.deb ... 971s Unpacking ssl-cert (1.1.3ubuntu1) ... 972s Setting up ssl-cert (1.1.3ubuntu1) ... 986s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 995s Setting up db5.3-util (5.3.28+dfsg2-9) ... 995s Setting up procmail (3.24-1ubuntu3) ... 995s Setting up python3-ptyprocess (0.7.0-6) ... 999s Setting up db-util (1:5.3.21ubuntu2) ... 999s Setting up sasl2-bin (2.1.28+dfsg1-9) ... 1017s saslauthd.service is a disabled or a static unit, not starting it. 1018s Setting up python3-pexpect (4.9-3) ... 1021s Processing triggers for man-db (2.13.0-1) ... 1041s autopkgtest [20:26:49]: test postfix: [----------------------- 1051s Reading package lists... 1053s Building dependency tree... 1053s Reading state information... 1057s Solving dependencies... 1058s The following additional packages will be installed: 1058s libnsl2 1058s Suggested packages: 1058s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 1058s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 1058s postfix-pgsql postfix-sqlite 1058s The following NEW packages will be installed: 1058s libnsl2 postfix 1059s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 1059s Need to get 1337 kB of archives. 1059s After this operation, 3808 kB of additional disk space will be used. 1059s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 libnsl2 riscv64 1.3.0-3build3 [43.3 kB] 1059s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 postfix riscv64 3.9.1-10ubuntu1 [1293 kB] 1066s Preconfiguring packages ... 1069s Fetched 1337 kB in 1s (1020 kB/s) 1070s Selecting previously unselected package libnsl2:riscv64. 1071s (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 ... 75750 files and directories currently installed.) 1071s Preparing to unpack .../libnsl2_1.3.0-3build3_riscv64.deb ... 1071s Unpacking libnsl2:riscv64 (1.3.0-3build3) ... 1071s Selecting previously unselected package postfix. 1072s Preparing to unpack .../postfix_3.9.1-10ubuntu1_riscv64.deb ... 1072s Unpacking postfix (3.9.1-10ubuntu1) ... 1074s Setting up libnsl2:riscv64 (1.3.0-3build3) ... 1074s Setting up postfix (3.9.1-10ubuntu1) ... 1084s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 1095s info: Selecting UID from range 100 to 999 ... 1095s 1095s info: Selecting GID from range 100 to 999 ... 1095s info: Adding system user `postfix' (UID 107) ... 1095s info: Adding new group `postfix' (GID 111) ... 1095s info: Adding new user `postfix' (UID 107) with group `postfix' ... 1096s info: Not creating home directory `/var/spool/postfix'. 1097s info: Selecting GID from range 100 to 999 ... 1098s info: Adding group `postdrop' (GID 112) ... 1099s Creating /etc/postfix/dynamicmaps.cf 1099s setting myhostname: localhost 1100s setting alias maps 1100s setting alias database 1100s mailname is not a fully qualified domain name. Not changing /etc/mailname. 1100s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 1100s setting relayhost: 1100s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 1101s setting mailbox_command 1101s setting mailbox_size_limit: 0 1101s setting recipient_delimiter: + 1103s setting inet_interfaces: all 1105s setting inet_protocols: all 1105s /etc/aliases does not exist, creating it. 1106s WARNING: /etc/aliases exists, but does not have a root alias. 1106s 1106s Postfix (main.cf) is now set up with a default configuration. If you need to 1106s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 1106s Postfix configuration values, see postconf(1). 1106s 1106s After modifying main.cf, be sure to run 'systemctl reload postfix'. 1106s 1108s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 1112s Processing triggers for ufw (0.36.2-9) ... 1115s Processing triggers for man-db (2.13.0-1) ... 1121s Processing triggers for libc-bin (2.41-1ubuntu2) ... 1122s Processing triggers for postfix (3.9.1-10ubuntu1) ... 1122s Restarting postfix 1169s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 1539s ............... 1539s ---------------------------------------------------------------------- 1539s Ran 15 tests in 343.642s 1539s 1539s OK 1540s ===== postfix.log contents: 1540s Mar 15 20:28:47 localhost postfix/master[2424]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:28:57 localhost postfix/postfix-script[2519]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 1540s Mar 15 20:29:58 localhost postfix/postfix-script[2979]: stopping the Postfix mail system 1540s Mar 15 20:29:58 localhost postfix/master[2424]: terminating on signal 15 1540s Mar 15 20:30:20 localhost postfix/master[3109]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:30:23 localhost postfix/smtpd[3114]: initializing the server-side TLS engine 1540s Mar 15 20:30:23 localhost postfix/smtpd[3114]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: initializing the server-side TLS engine 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:30:24 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:30:24 localhost postfix/smtpd[3114]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 1540s Mar 15 20:30:27 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:27 localhost postfix/smtpd[3117]: 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]> 1540s Mar 15 20:30:27 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 1540s Mar 15 20:30:30 localhost postfix/smtpd[3114]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:30 localhost postfix/smtpd[3114]: 90BD13E2BD: client=localhost[127.0.0.1] 1540s Mar 15 20:30:30 localhost postfix/smtpd[3114]: 90BD13E2BD: 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]> 1540s Mar 15 20:30:30 localhost postfix/cleanup[3159]: 90BD13E2BD: message-id=<20250315203030.90BD13E2BD@localhost> 1540s Mar 15 20:30:30 localhost postfix/qmgr[3112]: 90BD13E2BD: from=, size=380, nrcpt=1 (queue active) 1540s Mar 15 20:30:32 localhost postfix/local[3160]: 90BD13E2BD: to=, orig_to=, relay=local, delay=1.7, delays=0.32/0.2/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:30:32 localhost postfix/qmgr[3112]: 90BD13E2BD: removed 1540s Mar 15 20:30:33 localhost postfix/smtpd[3114]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 1540s Mar 15 20:30:35 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:37 localhost postfix/smtpd[3117]: warning: SASL authentication failure: Password verification failed 1540s Mar 15 20:30:37 localhost postfix/smtpd[3117]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 1540s Mar 15 20:30:40 localhost postfix/smtpd[3117]: warning: SASL authentication failure: Password verification failed 1540s Mar 15 20:30:40 localhost postfix/smtpd[3117]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tcjbdqhk 1540s Mar 15 20:30:42 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 1540s Mar 15 20:30:44 localhost postfix/smtpd[3114]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:45 localhost postfix/smtpd[3114]: C49963E2BD: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tsbomwow 1540s Mar 15 20:30:45 localhost postfix/smtpd[3114]: C49963E2BD: 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]> 1540s Mar 15 20:30:45 localhost postfix/cleanup[3159]: C49963E2BD: message-id=<20250315203045.C49963E2BD@localhost> 1540s Mar 15 20:30:45 localhost postfix/qmgr[3112]: C49963E2BD: from=, size=416, nrcpt=1 (queue active) 1540s Mar 15 20:30:47 localhost postfix/local[3160]: C49963E2BD: to=, orig_to=, relay=local, delay=1.3, delays=0.11/0.01/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:30:47 localhost postfix/qmgr[3112]: C49963E2BD: removed 1540s Mar 15 20:30:48 localhost postfix/smtpd[3114]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 1540s Mar 15 20:30:50 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:50 localhost postfix/smtpd[3117]: setting up TLS connection from localhost[127.0.0.1] 1540s Mar 15 20:30:50 localhost postfix/smtpd[3117]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 1540s Mar 15 20:30:50 localhost postfix/smtpd[3117]: SSL_accept:before SSL initialization 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:before SSL initialization 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS read client hello 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS write server hello 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS write change cipher spec 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:TLSv1.3 write encrypted extensions 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS write certificate 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:TLSv1.3 write server certificate verify 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS write finished 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:TLSv1.3 early data 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:TLSv1.3 early data 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS read finished 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1742072450 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: SSL_accept:SSLv3/TLS write session ticket 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: 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 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: 8C0AF3E2BD: client=localhost[127.0.0.1] 1540s Mar 15 20:30:51 localhost postfix/smtpd[3117]: 8C0AF3E2BD: 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]> 1540s Mar 15 20:30:51 localhost postfix/cleanup[3159]: 8C0AF3E2BD: message-id=<20250315203051.8C0AF3E2BD@localhost> 1540s Mar 15 20:30:51 localhost postfix/qmgr[3112]: 8C0AF3E2BD: from=, size=381, nrcpt=1 (queue active) 1540s Mar 15 20:30:52 localhost postfix/local[3160]: 8C0AF3E2BD: to=, orig_to=, relay=local, delay=1.2, delays=0.09/0.01/0/1.1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:30:52 localhost postfix/qmgr[3112]: 8C0AF3E2BD: removed 1540s Mar 15 20:30:54 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 1540s Mar 15 20:30:56 localhost postfix/smtpd[3114]: connect from localhost[127.0.0.1] 1540s Mar 15 20:30:58 localhost postfix/smtpd[3114]: 5306A3E2BD: client=localhost[127.0.0.1] 1540s Mar 15 20:30:58 localhost postfix/smtpd[3114]: 5306A3E2BD: 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]> 1540s Mar 15 20:30:58 localhost postfix/cleanup[3159]: 5306A3E2BD: message-id=<20250315203058.5306A3E2BD@localhost> 1540s Mar 15 20:30:58 localhost postfix/qmgr[3112]: 5306A3E2BD: from=, size=380, nrcpt=1 (queue active) 1540s Mar 15 20:30:58 localhost postfix/cleanup[3159]: 69AC93E2BF: message-id=<20250315203058.5306A3E2BD@localhost> 1540s Mar 15 20:30:58 localhost postfix/qmgr[3112]: 69AC93E2BF: from=, size=503, nrcpt=1 (queue active) 1540s Mar 15 20:30:58 localhost postfix/local[3160]: 5306A3E2BD: to=, orig_to=, relay=local, delay=0.13, delays=0.07/0.02/0/0.05, dsn=2.0.0, status=sent (forwarded as 69AC93E2BF) 1540s Mar 15 20:30:58 localhost postfix/qmgr[3112]: 5306A3E2BD: removed 1540s Mar 15 20:30:59 localhost postfix/local[3160]: 69AC93E2BF: to=, orig_to=, relay=local, delay=1.2, delays=0.02/0.01/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:30:59 localhost postfix/qmgr[3112]: 69AC93E2BF: removed 1540s Mar 15 20:31:01 localhost postfix/smtpd[3114]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 1540s Mar 15 20:31:03 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:03 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:31:05 localhost postfix/smtpd[3114]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:07 localhost postfix/smtpd[3114]: 308843E2BF: client=localhost[127.0.0.1] 1540s Mar 15 20:31:07 localhost postfix/cleanup[3159]: 308843E2BF: message-id=<20250315203107.308843E2BF@localhost> 1540s Mar 15 20:31:07 localhost postfix/qmgr[3112]: 308843E2BF: from=, size=389, nrcpt=1 (queue active) 1540s Mar 15 20:31:09 localhost postfix/smtpd[3114]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 1540s Mar 15 20:31:11 localhost postfix/smtpd[3117]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:11 localhost postfix/smtpd[3117]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:31:24 localhost postfix/local[3160]: 308843E2BF: to=, orig_to=, relay=local, delay=18, delays=0.29/0.05/0/17, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:31:24 localhost postfix/qmgr[3112]: 308843E2BF: removed 1540s Mar 15 20:31:27 localhost postfix/postfix-script[3360]: stopping the Postfix mail system 1540s Mar 15 20:31:27 localhost postfix/master[3109]: terminating on signal 15 1540s Mar 15 20:31:48 localhost postfix/master[3501]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:31:50 localhost postfix/smtpd[3506]: initializing the server-side TLS engine 1540s Mar 15 20:31:51 localhost postfix/smtpd[3506]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:51 localhost postfix/smtpd[3509]: initializing the server-side TLS engine 1540s Mar 15 20:31:52 localhost postfix/smtpd[3509]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:52 localhost postfix/smtpd[3509]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:31:52 localhost postfix/smtpd[3509]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:31:52 localhost postfix/smtpd[3506]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 1540s Mar 15 20:31:52 localhost postfix/smtpd[3506]: warning: SASL authentication failure: incorrect digest response 1540s Mar 15 20:31:52 localhost postfix/smtpd[3506]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tjfgupbu 1540s Mar 15 20:31:52 localhost postfix/smtpd[3506]: E3B883E2C1: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tjfgupbu 1540s Mar 15 20:31:52 localhost postfix/smtpd[3506]: E3B883E2C1: 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]> 1540s Mar 15 20:31:53 localhost postfix/cleanup[3511]: E3B883E2C1: message-id=<20250315203152.E3B883E2C1@localhost> 1540s Mar 15 20:31:53 localhost postfix/qmgr[3503]: E3B883E2C1: from=, size=416, nrcpt=1 (queue active) 1540s Mar 15 20:31:54 localhost postfix/local[3512]: E3B883E2C1: to=, orig_to=, relay=local, delay=1.7, delays=0.36/0.19/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:31:54 localhost postfix/qmgr[3503]: E3B883E2C1: removed 1540s Mar 15 20:31:56 localhost postfix/smtpd[3506]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 1540s Mar 15 20:31:57 localhost postfix/smtpd[3509]: connect from localhost[127.0.0.1] 1540s Mar 15 20:31:57 localhost postfix/smtpd[3509]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:32:11 localhost postfix/postfix-script[3552]: stopping the Postfix mail system 1540s Mar 15 20:32:12 localhost postfix/master[3501]: terminating on signal 15 1540s Mar 15 20:32:31 localhost postfix/master[3679]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:32:34 localhost postfix/smtpd[3686]: initializing the server-side TLS engine 1540s Mar 15 20:32:35 localhost postfix/smtpd[3686]: connect from localhost[127.0.0.1] 1540s Mar 15 20:32:35 localhost postfix/smtpd[3689]: initializing the server-side TLS engine 1540s Mar 15 20:32:36 localhost postfix/smtpd[3689]: connect from localhost[127.0.0.1] 1540s Mar 15 20:32:36 localhost postfix/smtpd[3689]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:32:36 localhost postfix/smtpd[3689]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:32:36 localhost postfix/smtpd[3686]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 1540s Mar 15 20:32:38 localhost postfix/smtpd[3689]: connect from localhost[127.0.0.1] 1540s Mar 15 20:32:38 localhost postfix/smtpd[3689]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:32:52 localhost postfix/postfix-script[3727]: stopping the Postfix mail system 1540s Mar 15 20:32:52 localhost postfix/master[3679]: terminating on signal 15 1540s Mar 15 20:33:14 localhost postfix/master[3856]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:33:17 localhost postfix/smtpd[3861]: initializing the server-side TLS engine 1540s Mar 15 20:33:18 localhost postfix/smtpd[3861]: connect from localhost[127.0.0.1] 1540s Mar 15 20:33:18 localhost postfix/smtpd[3864]: initializing the server-side TLS engine 1540s Mar 15 20:33:18 localhost postfix/smtpd[3864]: connect from localhost[127.0.0.1] 1540s Mar 15 20:33:18 localhost postfix/smtpd[3864]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:33:18 localhost postfix/smtpd[3864]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:33:18 localhost postfix/smtpd[3861]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 1540s Mar 15 20:33:20 localhost postfix/smtpd[3864]: connect from localhost[127.0.0.1] 1540s Mar 15 20:33:20 localhost postfix/smtpd[3864]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:33:34 localhost postfix/postfix-script[3902]: stopping the Postfix mail system 1540s Mar 15 20:33:34 localhost postfix/master[3856]: terminating on signal 15 1540s Mar 15 20:33:53 localhost postfix/master[4030]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:33:56 localhost postfix/smtpd[4035]: initializing the server-side TLS engine 1540s Mar 15 20:33:57 localhost postfix/smtpd[4035]: connect from localhost[127.0.0.1] 1540s Mar 15 20:33:57 localhost postfix/smtpd[4038]: initializing the server-side TLS engine 1540s Mar 15 20:33:57 localhost postfix/smtpd[4038]: connect from localhost[127.0.0.1] 1540s Mar 15 20:33:57 localhost postfix/smtpd[4038]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:33:57 localhost postfix/smtpd[4038]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:33:57 localhost postfix/smtpd[4035]: warning: SASL authentication failure: Password verification failed 1540s Mar 15 20:33:57 localhost postfix/smtpd[4035]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 1540s Mar 15 20:33:57 localhost postfix/smtpd[4035]: warning: SASL authentication failure: Password verification failed 1540s Mar 15 20:33:57 localhost postfix/smtpd[4035]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=tdihaccz 1540s Mar 15 20:33:58 localhost postfix/smtpd[4035]: 0A7733E2C1: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=tdihaccz 1540s Mar 15 20:33:58 localhost postfix/smtpd[4035]: 0A7733E2C1: 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]> 1540s Mar 15 20:33:58 localhost postfix/cleanup[4040]: 0A7733E2C1: message-id=<20250315203358.0A7733E2C1@localhost> 1540s Mar 15 20:33:58 localhost postfix/qmgr[4032]: 0A7733E2C1: from=, size=416, nrcpt=1 (queue active) 1540s Mar 15 20:33:59 localhost postfix/local[4041]: 0A7733E2C1: to=, orig_to=, relay=local, delay=1.7, delays=0.31/0.2/0/1.2, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 1540s Mar 15 20:33:59 localhost postfix/qmgr[4032]: 0A7733E2C1: removed 1540s Mar 15 20:34:01 localhost postfix/smtpd[4035]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 1540s Mar 15 20:34:03 localhost postfix/smtpd[4038]: connect from localhost[127.0.0.1] 1540s Mar 15 20:34:03 localhost postfix/smtpd[4038]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1540s Mar 15 20:34:18 localhost postfix/postfix-script[4081]: stopping the Postfix mail system 1540s Mar 15 20:34:18 localhost postfix/master[4030]: terminating on signal 15 1540s Mar 15 20:34:40 localhost postfix/master[4208]: daemon started -- version 3.9.1, configuration /etc/postfix 1540s Mar 15 20:34:43 localhost postfix/smtpd[4215]: initializing the server-side TLS engine 1540s Mar 15 20:34:44 localhost postfix/smtpd[4215]: connect from localhost[127.0.0.1] 1540s Mar 15 20:34:44 localhost postfix/smtpd[4218]: initializing the server-side TLS engine 1540s Mar 15 20:34:45 localhost postfix/smtpd[4218]: connect from localhost[127.0.0.1] 1540s Mar 15 20:34:45 localhost postfix/smtpd[4218]: lost connection after CONNECT from localhost[127.0.0.1] 1540s Mar 15 20:34:45 localhost postfix/smtpd[4218]: disconnect from localhost[127.0.0.1] commands=0/0 1540s Mar 15 20:34:45 localhost postfix/smtpd[4215]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 1540s Mar 15 20:34:45 localhost postfix/smtpd[4215]: warning: SASL authentication failure: Parse error 1540s Mar 15 20:34:45 localhost postfix/smtpd[4215]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 1540s Mar 15 20:34:45 localhost postfix/smtpd[4215]: lost connection after AUTH from localhost[127.0.0.1] 1540s Mar 15 20:34:45 localhost postfix/smtpd[4215]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 1540s Mar 15 20:34:47 localhost postfix/smtpd[4218]: connect from localhost[127.0.0.1] 1540s Mar 15 20:35:07 localhost postfix/smtpd[4218]: disconnect from localhost[127.0.0.1] quit=1 commands=1 1541s autopkgtest [20:35:09]: test postfix: -----------------------] 1544s postfix PASS 1544s autopkgtest [20:35:12]: test postfix: - - - - - - - - - - results - - - - - - - - - - 1546s autopkgtest [20:35:14]: @@@@@@@@@@@@@@@@@@@@ summary 1546s postfix PASS 1578s nova [W] Using flock in prodstack6-riscv64 1578s Creating nova instance adt-plucky-riscv64-postfix-20250315-200928-juju-7f2275-prod-proposed-migration-environment-15-b7669d10-1696-46c8-8ff9-af57e9e9ba2c from image adt/ubuntu-plucky-riscv64-server-20250315.img (UUID 306bcabb-e549-4f8c-9dd8-573d52716ea6)... 1578s nova [W] Timed out waiting for 612e7d56-caa1-45ec-abfa-49eb65faeee1 to get deleted.