0s autopkgtest [20:33:16]: starting date and time: 2025-02-21 20:33:16+0000 0s autopkgtest [20:33:16]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [20:33:16]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._ncb08ix/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:build-essential --apt-upgrade libgssglue --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=build-essential/12.12 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-18.secgroup --name adt-plucky-s390x-libgssglue-20250221-203316-juju-7f2275-prod-proposed-migration-environment-15-dbdeb46c-9ab9-4831-b3e2-86732dda50ca --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --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/ 103s autopkgtest [20:34:59]: testbed dpkg architecture: s390x 104s autopkgtest [20:35:00]: testbed apt version: 2.9.30ubuntu1 104s autopkgtest [20:35:00]: @@@@@@@@@@@@@@@@@@@@ test bed setup 104s autopkgtest [20:35:00]: testbed release detected to be: None 106s autopkgtest [20:35:01]: updating testbed package index (apt update) 106s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 106s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 106s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 106s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 106s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 106s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [708 kB] 106s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.8 kB] 106s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [85.6 kB] 106s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [129 kB] 106s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 106s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [633 kB] 106s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4436 B] 106s Fetched 1689 kB in 1s (1828 kB/s) 107s Reading package lists... 107s + lsb_release --codename --short 107s + RELEASE=plucky 107s + cat 107s + [ plucky != trusty ] 107s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 107s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Calculating upgrade... 108s Calculating upgrade... 108s The following packages were automatically installed and are no longer required: 108s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 108s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 108s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 108s linux-tools-6.11.0-8-generic 108s Use 'sudo apt autoremove' to remove them. 108s The following packages will be upgraded: 108s apparmor base-files gcc-14-base libapparmor1 libgnutls30t64 liblsof0 libnss3 108s lsof motd-news-config ucf 108s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 108s Need to get 3746 kB of archives. 108s After this operation, 7168 B of additional disk space will be used. 108s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x motd-news-config all 13.6ubuntu1 [5168 B] 108s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x base-files s390x 13.6ubuntu1 [75.4 kB] 108s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu3 [53.6 kB] 108s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libapparmor1 s390x 4.1.0~beta5-0ubuntu5 [54.3 kB] 108s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls30t64 s390x 3.8.9-2ubuntu2 [956 kB] 109s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ucf all 3.0050 [43.5 kB] 109s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x apparmor s390x 4.1.0~beta5-0ubuntu5 [758 kB] 109s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x lsof s390x 4.99.4+dfsg-2 [243 kB] 109s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x liblsof0 s390x 4.99.4+dfsg-2 [58.6 kB] 109s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libnss3 s390x 2:3.108-1ubuntu1 [1499 kB] 109s Preconfiguring packages ... 109s Fetched 3746 kB in 1s (4152 kB/s) 109s (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 ... 81030 files and directories currently installed.) 109s Preparing to unpack .../motd-news-config_13.6ubuntu1_all.deb ... 109s Unpacking motd-news-config (13.6ubuntu1) over (13.5ubuntu3) ... 109s Preparing to unpack .../base-files_13.6ubuntu1_s390x.deb ... 109s Unpacking base-files (13.6ubuntu1) over (13.5ubuntu3) ... 109s Setting up base-files (13.6ubuntu1) ... 109s Updating /root/.profile to current default. 110s motd-news.service is a disabled or a static unit not running, not starting it. 110s (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 ... 81030 files and directories currently installed.) 110s Preparing to unpack .../0-gcc-14-base_14.2.0-17ubuntu3_s390x.deb ... 110s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu3) over (14.2.0-17ubuntu1) ... 110s Preparing to unpack .../1-libapparmor1_4.1.0~beta5-0ubuntu5_s390x.deb ... 110s Unpacking libapparmor1:s390x (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 110s Preparing to unpack .../2-libgnutls30t64_3.8.9-2ubuntu2_s390x.deb ... 110s Unpacking libgnutls30t64:s390x (3.8.9-2ubuntu2) over (3.8.9-2ubuntu1) ... 110s Preparing to unpack .../3-ucf_3.0050_all.deb ... 110s Unpacking ucf (3.0050) over (3.0049) ... 110s Preparing to unpack .../4-apparmor_4.1.0~beta5-0ubuntu5_s390x.deb ... 110s Unpacking apparmor (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 110s Preparing to unpack .../5-lsof_4.99.4+dfsg-2_s390x.deb ... 110s Unpacking lsof (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 110s Preparing to unpack .../6-liblsof0_4.99.4+dfsg-2_s390x.deb ... 110s Unpacking liblsof0 (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 110s Preparing to unpack .../7-libnss3_2%3a3.108-1ubuntu1_s390x.deb ... 110s Unpacking libnss3:s390x (2:3.108-1ubuntu1) over (2:3.107-1ubuntu1) ... 111s Setting up libgnutls30t64:s390x (3.8.9-2ubuntu2) ... 111s Setting up motd-news-config (13.6ubuntu1) ... 111s Setting up libapparmor1:s390x (4.1.0~beta5-0ubuntu5) ... 111s Setting up gcc-14-base:s390x (14.2.0-17ubuntu3) ... 111s Setting up liblsof0 (4.99.4+dfsg-2) ... 111s Setting up libnss3:s390x (2:3.108-1ubuntu1) ... 111s Setting up apparmor (4.1.0~beta5-0ubuntu5) ... 111s Installing new version of config file /etc/apparmor.d/fusermount3 ... 111s Reloading AppArmor profiles 113s Setting up ucf (3.0050) ... 113s Setting up lsof (4.99.4+dfsg-2) ... 113s Processing triggers for libc-bin (2.40-4ubuntu1) ... 113s Processing triggers for systemd (257.2-3ubuntu1) ... 113s Processing triggers for man-db (2.13.0-1) ... 114s Processing triggers for plymouth-theme-ubuntu-text (24.004.60-2ubuntu5) ... 114s Processing triggers for install-info (7.1.1-1) ... 114s Processing triggers for initramfs-tools (0.145ubuntu2) ... 114s update-initramfs: Generating /boot/initrd.img-6.12.0-15-generic 114s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 118s Using config file '/etc/zipl.conf' 118s Building bootmap in '/boot' 118s Adding IPL section 'ubuntu' (default) 118s Preparing boot device for LD-IPL: vda (0000). 118s Done. 118s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 118s + /usr/lib/apt/apt-helper analyze-pattern ?true 118s + uname -r 118s + sed s/\./\\./g 118s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 118s + apt list ?obsolete 118s + tail -n+2 118s + + grep -v ^linux-.*6\.12\.0-15-generic.* 118s cut -d/ -f1 118s + true 118s + obsolete_pkgs= 118s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 118s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s Solving dependencies... 119s The following packages will be REMOVED: 119s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 119s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 119s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 119s linux-tools-6.11.0-8-generic* 119s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 119s After this operation, 167 MB disk space will be freed. 119s (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 ... 81030 files and directories currently installed.) 119s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 119s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 119s Removing libpython3.12t64:s390x (3.12.9-1) ... 119s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 119s Removing libnsl2:s390x (1.3.0-3build3) ... 119s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 119s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 119s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 120s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 120s Processing triggers for libc-bin (2.40-4ubuntu1) ... 120s (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 ... 55930 files and directories currently installed.) 120s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 120s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 120s + grep -q trusty /etc/lsb-release 120s + [ ! -d /usr/share/doc/unattended-upgrades ] 120s + [ ! -d /usr/share/doc/lxd ] 120s + [ ! -d /usr/share/doc/lxd-client ] 120s + [ ! -d /usr/share/doc/snapd ] 120s + type iptables 120s + cat 120s + chmod 755 /etc/rc.local 120s + . /etc/rc.local 120s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 120s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 120s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 120s + uname -m 120s + [ s390x = ppc64le ] 120s + [ -d /run/systemd/system ] 120s + systemd-detect-virt --quiet --vm 120s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 120s + cat 120s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 120s + echo COMPRESS=lz4 120s autopkgtest [20:35:16]: upgrading testbed (apt dist-upgrade and autopurge) 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 121s Starting 2 pkgProblemResolver with broken count: 0 121s Done 121s Entering ResolveByKeep 121s 121s Calculating upgrade... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Starting pkgProblemResolver with broken count: 0 122s Starting 2 pkgProblemResolver with broken count: 0 122s Done 122s Solving dependencies... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s autopkgtest [20:35:18]: rebooting testbed after setup commands that affected boot 140s autopkgtest [20:35:36]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 15:05:57 UTC 2025 143s autopkgtest [20:35:39]: @@@@@@@@@@@@@@@@@@@@ apt-source libgssglue 145s Get:1 http://ftpmaster.internal/ubuntu plucky/main libgssglue 0.9-1build1 (dsc) [2209 B] 145s Get:2 http://ftpmaster.internal/ubuntu plucky/main libgssglue 0.9-1build1 (tar) [43.7 kB] 145s Get:3 http://ftpmaster.internal/ubuntu plucky/main libgssglue 0.9-1build1 (diff) [11.0 kB] 145s gpgv: Signature made Mon Apr 8 16:05:00 2024 UTC 145s gpgv: using RSA key 4FB588A84C2DDE79A74C77876FA458DD1DB03F71 145s gpgv: issuer "juliank@ubuntu.com" 145s gpgv: Can't check signature: No public key 145s dpkg-source: warning: cannot verify inline signature for ./libgssglue_0.9-1build1.dsc: no acceptable signature found 145s autopkgtest [20:35:41]: testing package libgssglue version 0.9-1build1 146s autopkgtest [20:35:42]: build not needed 147s autopkgtest [20:35:43]: test libgssglue: preparing testbed 148s Reading package lists... 148s Building dependency tree... 148s Reading state information... 148s Starting pkgProblemResolver with broken count: 0 148s Starting 2 pkgProblemResolver with broken count: 0 148s Done 148s The following NEW packages will be installed: 148s build-essential cpp cpp-14 cpp-14-s390x-linux-gnu cpp-s390x-linux-gnu g++ 148s g++-14 g++-14-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-14 148s gcc-14-s390x-linux-gnu gcc-s390x-linux-gnu libasan8 libcc1-0 libgcc-14-dev 148s libgomp1 libgssglue-dev libgssglue1 libisl23 libitm1 libmpc3 libpkgconf3 148s libstdc++-14-dev libubsan1 pkgconf pkgconf-bin 148s 0 upgraded, 27 newly installed, 0 to remove and 0 not upgraded. 148s Need to get 48.9 MB of archives. 148s After this operation, 156 MB of additional disk space will be used. 148s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libisl23 s390x 0.27-1 [704 kB] 149s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libmpc3 s390x 1.3.1-1build2 [57.8 kB] 149s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [9572 kB] 149s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14 s390x 14.2.0-17ubuntu3 [1028 B] 149s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [5556 B] 149s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x cpp s390x 4:14.2.0-1ubuntu1 [22.4 kB] 149s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libcc1-0 s390x 15-20250213-1ubuntu1 [49.2 kB] 149s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libgomp1 s390x 15-20250213-1ubuntu1 [151 kB] 149s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libitm1 s390x 15-20250213-1ubuntu1 [31.2 kB] 149s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libasan8 s390x 15-20250213-1ubuntu1 [2970 kB] 149s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libubsan1 s390x 15-20250213-1ubuntu1 [1213 kB] 149s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-14-dev s390x 14.2.0-17ubuntu3 [1037 kB] 149s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [18.7 MB] 150s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14 s390x 14.2.0-17ubuntu3 [526 kB] 150s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [1204 B] 150s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x gcc s390x 4:14.2.0-1ubuntu1 [5004 B] 150s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++-14-dev s390x 14.2.0-17ubuntu3 [2611 kB] 150s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [11.0 MB] 150s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14 s390x 14.2.0-17ubuntu3 [21.8 kB] 150s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x g++-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [956 B] 150s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x g++ s390x 4:14.2.0-1ubuntu1 [1080 B] 150s Get:22 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x build-essential s390x 12.12 [4610 B] 150s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libgssglue1 s390x 0.9-1build1 [20.6 kB] 150s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libgssglue-dev s390x 0.9-1build1 [29.3 kB] 150s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libpkgconf3 s390x 1.8.1-4 [31.2 kB] 150s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x pkgconf-bin s390x 1.8.1-4 [21.5 kB] 150s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x pkgconf s390x 1.8.1-4 [16.7 kB] 150s Fetched 48.9 MB in 2s (27.2 MB/s) 150s Selecting previously unselected package libisl23:s390x. 150s (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 ... 55928 files and directories currently installed.) 150s Preparing to unpack .../00-libisl23_0.27-1_s390x.deb ... 150s Unpacking libisl23:s390x (0.27-1) ... 150s Selecting previously unselected package libmpc3:s390x. 150s Preparing to unpack .../01-libmpc3_1.3.1-1build2_s390x.deb ... 150s Unpacking libmpc3:s390x (1.3.1-1build2) ... 150s Selecting previously unselected package cpp-14-s390x-linux-gnu. 150s Preparing to unpack .../02-cpp-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 150s Unpacking cpp-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 150s Selecting previously unselected package cpp-14. 150s Preparing to unpack .../03-cpp-14_14.2.0-17ubuntu3_s390x.deb ... 150s Unpacking cpp-14 (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package cpp-s390x-linux-gnu. 151s Preparing to unpack .../04-cpp-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package cpp. 151s Preparing to unpack .../05-cpp_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking cpp (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package libcc1-0:s390x. 151s Preparing to unpack .../06-libcc1-0_15-20250213-1ubuntu1_s390x.deb ... 151s Unpacking libcc1-0:s390x (15-20250213-1ubuntu1) ... 151s Selecting previously unselected package libgomp1:s390x. 151s Preparing to unpack .../07-libgomp1_15-20250213-1ubuntu1_s390x.deb ... 151s Unpacking libgomp1:s390x (15-20250213-1ubuntu1) ... 151s Selecting previously unselected package libitm1:s390x. 151s Preparing to unpack .../08-libitm1_15-20250213-1ubuntu1_s390x.deb ... 151s Unpacking libitm1:s390x (15-20250213-1ubuntu1) ... 151s Selecting previously unselected package libasan8:s390x. 151s Preparing to unpack .../09-libasan8_15-20250213-1ubuntu1_s390x.deb ... 151s Unpacking libasan8:s390x (15-20250213-1ubuntu1) ... 151s Selecting previously unselected package libubsan1:s390x. 151s Preparing to unpack .../10-libubsan1_15-20250213-1ubuntu1_s390x.deb ... 151s Unpacking libubsan1:s390x (15-20250213-1ubuntu1) ... 151s Selecting previously unselected package libgcc-14-dev:s390x. 151s Preparing to unpack .../11-libgcc-14-dev_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking libgcc-14-dev:s390x (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package gcc-14-s390x-linux-gnu. 151s Preparing to unpack .../12-gcc-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking gcc-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package gcc-14. 151s Preparing to unpack .../13-gcc-14_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking gcc-14 (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package gcc-s390x-linux-gnu. 151s Preparing to unpack .../14-gcc-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package gcc. 151s Preparing to unpack .../15-gcc_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking gcc (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package libstdc++-14-dev:s390x. 151s Preparing to unpack .../16-libstdc++-14-dev_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking libstdc++-14-dev:s390x (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package g++-14-s390x-linux-gnu. 151s Preparing to unpack .../17-g++-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking g++-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package g++-14. 151s Preparing to unpack .../18-g++-14_14.2.0-17ubuntu3_s390x.deb ... 151s Unpacking g++-14 (14.2.0-17ubuntu3) ... 151s Selecting previously unselected package g++-s390x-linux-gnu. 151s Preparing to unpack .../19-g++-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package g++. 151s Preparing to unpack .../20-g++_4%3a14.2.0-1ubuntu1_s390x.deb ... 151s Unpacking g++ (4:14.2.0-1ubuntu1) ... 151s Selecting previously unselected package build-essential. 151s Preparing to unpack .../21-build-essential_12.12_s390x.deb ... 151s Unpacking build-essential (12.12) ... 151s Selecting previously unselected package libgssglue1:s390x. 151s Preparing to unpack .../22-libgssglue1_0.9-1build1_s390x.deb ... 151s Unpacking libgssglue1:s390x (0.9-1build1) ... 151s Selecting previously unselected package libgssglue-dev:s390x. 151s Preparing to unpack .../23-libgssglue-dev_0.9-1build1_s390x.deb ... 151s Unpacking libgssglue-dev:s390x (0.9-1build1) ... 151s Selecting previously unselected package libpkgconf3:s390x. 151s Preparing to unpack .../24-libpkgconf3_1.8.1-4_s390x.deb ... 151s Unpacking libpkgconf3:s390x (1.8.1-4) ... 151s Selecting previously unselected package pkgconf-bin. 151s Preparing to unpack .../25-pkgconf-bin_1.8.1-4_s390x.deb ... 151s Unpacking pkgconf-bin (1.8.1-4) ... 151s Selecting previously unselected package pkgconf:s390x. 151s Preparing to unpack .../26-pkgconf_1.8.1-4_s390x.deb ... 151s Unpacking pkgconf:s390x (1.8.1-4) ... 151s Setting up libgomp1:s390x (15-20250213-1ubuntu1) ... 151s Setting up libpkgconf3:s390x (1.8.1-4) ... 151s Setting up libmpc3:s390x (1.3.1-1build2) ... 151s Setting up libgssglue1:s390x (0.9-1build1) ... 151s Setting up pkgconf-bin (1.8.1-4) ... 151s Setting up libubsan1:s390x (15-20250213-1ubuntu1) ... 151s Setting up libasan8:s390x (15-20250213-1ubuntu1) ... 151s Setting up libisl23:s390x (0.27-1) ... 151s Setting up libcc1-0:s390x (15-20250213-1ubuntu1) ... 151s Setting up libitm1:s390x (15-20250213-1ubuntu1) ... 151s Setting up libgssglue-dev:s390x (0.9-1build1) ... 151s Setting up pkgconf:s390x (1.8.1-4) ... 151s Setting up cpp-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 151s Setting up cpp-14 (14.2.0-17ubuntu3) ... 151s Setting up libgcc-14-dev:s390x (14.2.0-17ubuntu3) ... 151s Setting up libstdc++-14-dev:s390x (14.2.0-17ubuntu3) ... 151s Setting up cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Setting up gcc-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 151s Setting up gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Setting up g++-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 151s Setting up cpp (4:14.2.0-1ubuntu1) ... 151s Setting up g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 151s Setting up gcc-14 (14.2.0-17ubuntu3) ... 151s Setting up g++-14 (14.2.0-17ubuntu3) ... 151s Setting up gcc (4:14.2.0-1ubuntu1) ... 151s Setting up g++ (4:14.2.0-1ubuntu1) ... 151s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 151s Setting up build-essential (12.12) ... 151s Processing triggers for man-db (2.13.0-1) ... 152s Processing triggers for libc-bin (2.40-4ubuntu1) ... 153s autopkgtest [20:35:49]: test libgssglue: [----------------------- 153s -I/usr/include/gssglue 153s -I/usr/include/gssglue 153s PASS: pkg-config --cflags libgssglue 153s -lgssglue -ldl 153s -lgssglue -ldl 153s PASS: pkg-config --libs libgssglue 153s tests/generic.c 153s PASS: gcc tests/generic.c 153s PASS: gss_create_empty_oid_set 153s PASS: gss_test_oid_set_member 153s PASS: gss_test_oid_set_member n==0 153s PASS: gss_add_oid_set_member() OK 153s PASS: gss_test_oid_set_member() OK 153s OID present in set with the OID added to it => 1 153s PASS: gss_test_oid_set_member() OK 153s PASS: gss_test_oid_set_member() OK 153s Another OID present in set without the OID => 0 153s PASS: gss_test_oid_set_member() OK 153s PASS: gss_add_oid_set_member() OK 153s PASS: gss_test_oid_set_member() OK 153s Another OID present in set with it added => 1 153s PASS: gss_test_oid_set_member() OK 153s PASS: gss_test_oid_set_member() OK 153s First OID present in set => 1 153s PASS: gss_test_oid_set_member() OK 153s PASS: gss_release_oid_set() OK 153s PASS: gss_indicate_mechs() OK 153s PASS: gss_release_oid_set() OK 153s PASS: gss_import_name() OK 153s PASS: gss_display_name() OK 153s display_name() => 27: imap@server.example.org@FOO 153s PASS: gss_release_buffer() OK 153s PASS: gss_release_name() OK 153s Basic self tests done with 0 errors 153s PASS: tests/generic.c 154s autopkgtest [20:35:50]: test libgssglue: -----------------------] 154s autopkgtest [20:35:50]: test libgssglue: - - - - - - - - - - results - - - - - - - - - - 154s libgssglue PASS 154s autopkgtest [20:35:50]: test bad-configs: preparing testbed 283s autopkgtest [20:37:59]: testbed dpkg architecture: s390x 283s autopkgtest [20:37:59]: testbed apt version: 2.9.30ubuntu1 283s autopkgtest [20:37:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 284s autopkgtest [20:38:00]: testbed release detected to be: plucky 284s autopkgtest [20:38:00]: updating testbed package index (apt update) 285s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 285s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 285s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 285s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 285s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [708 kB] 285s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 285s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.8 kB] 285s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [85.6 kB] 285s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [129 kB] 285s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 285s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [633 kB] 286s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4436 B] 286s Fetched 1689 kB in 1s (1439 kB/s) 286s Reading package lists... 287s + lsb_release --codename --short 287s + RELEASE=plucky 287s + cat 287s + [ plucky != trusty ] 287s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 287s Reading package lists... 287s Building dependency tree... 287s Reading state information... 287s Calculating upgrade... 287s Calculating upgrade... 287s The following packages were automatically installed and are no longer required: 287s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 287s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 287s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 287s linux-tools-6.11.0-8-generic 287s Use 'sudo apt autoremove' to remove them. 287s The following packages will be upgraded: 287s apparmor base-files gcc-14-base libapparmor1 libgnutls30t64 liblsof0 libnss3 287s lsof motd-news-config ucf 287s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 287s Need to get 3746 kB of archives. 287s After this operation, 7168 B of additional disk space will be used. 287s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x motd-news-config all 13.6ubuntu1 [5168 B] 288s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x base-files s390x 13.6ubuntu1 [75.4 kB] 288s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu3 [53.6 kB] 288s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libapparmor1 s390x 4.1.0~beta5-0ubuntu5 [54.3 kB] 288s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls30t64 s390x 3.8.9-2ubuntu2 [956 kB] 288s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ucf all 3.0050 [43.5 kB] 288s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x apparmor s390x 4.1.0~beta5-0ubuntu5 [758 kB] 288s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x lsof s390x 4.99.4+dfsg-2 [243 kB] 288s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x liblsof0 s390x 4.99.4+dfsg-2 [58.6 kB] 288s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libnss3 s390x 2:3.108-1ubuntu1 [1499 kB] 289s Preconfiguring packages ... 289s Fetched 3746 kB in 1s (3199 kB/s) 289s (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 ... 81030 files and directories currently installed.) 289s Preparing to unpack .../motd-news-config_13.6ubuntu1_all.deb ... 289s Unpacking motd-news-config (13.6ubuntu1) over (13.5ubuntu3) ... 289s Preparing to unpack .../base-files_13.6ubuntu1_s390x.deb ... 289s Unpacking base-files (13.6ubuntu1) over (13.5ubuntu3) ... 289s Setting up base-files (13.6ubuntu1) ... 289s Updating /root/.profile to current default. 289s motd-news.service is a disabled or a static unit not running, not starting it. 289s (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 ... 81030 files and directories currently installed.) 289s Preparing to unpack .../0-gcc-14-base_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu3) over (14.2.0-17ubuntu1) ... 289s Preparing to unpack .../1-libapparmor1_4.1.0~beta5-0ubuntu5_s390x.deb ... 289s Unpacking libapparmor1:s390x (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 289s Preparing to unpack .../2-libgnutls30t64_3.8.9-2ubuntu2_s390x.deb ... 289s Unpacking libgnutls30t64:s390x (3.8.9-2ubuntu2) over (3.8.9-2ubuntu1) ... 289s Preparing to unpack .../3-ucf_3.0050_all.deb ... 289s Unpacking ucf (3.0050) over (3.0049) ... 289s Preparing to unpack .../4-apparmor_4.1.0~beta5-0ubuntu5_s390x.deb ... 290s Unpacking apparmor (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 290s Preparing to unpack .../5-lsof_4.99.4+dfsg-2_s390x.deb ... 290s Unpacking lsof (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 290s Preparing to unpack .../6-liblsof0_4.99.4+dfsg-2_s390x.deb ... 290s Unpacking liblsof0 (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 290s Preparing to unpack .../7-libnss3_2%3a3.108-1ubuntu1_s390x.deb ... 290s Unpacking libnss3:s390x (2:3.108-1ubuntu1) over (2:3.107-1ubuntu1) ... 290s Setting up libgnutls30t64:s390x (3.8.9-2ubuntu2) ... 290s Setting up motd-news-config (13.6ubuntu1) ... 290s Setting up libapparmor1:s390x (4.1.0~beta5-0ubuntu5) ... 290s Setting up gcc-14-base:s390x (14.2.0-17ubuntu3) ... 290s Setting up liblsof0 (4.99.4+dfsg-2) ... 290s Setting up libnss3:s390x (2:3.108-1ubuntu1) ... 290s Setting up apparmor (4.1.0~beta5-0ubuntu5) ... 290s Installing new version of config file /etc/apparmor.d/fusermount3 ... 291s Reloading AppArmor profiles 292s Setting up ucf (3.0050) ... 292s Setting up lsof (4.99.4+dfsg-2) ... 292s Processing triggers for libc-bin (2.40-4ubuntu1) ... 292s Processing triggers for systemd (257.2-3ubuntu1) ... 292s Processing triggers for man-db (2.13.0-1) ... 293s Processing triggers for plymouth-theme-ubuntu-text (24.004.60-2ubuntu5) ... 293s Processing triggers for install-info (7.1.1-1) ... 293s Processing triggers for initramfs-tools (0.145ubuntu2) ... 293s update-initramfs: Generating /boot/initrd.img-6.12.0-15-generic 293s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 297s Using config file '/etc/zipl.conf' 297s Building bootmap in '/boot' 297s Adding IPL section 'ubuntu' (default) 297s Preparing boot device for LD-IPL: vda (0000). 297s Done. 297s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 297s + /usr/lib/apt/apt-helper analyze-pattern ?true 297s + uname -r 297s + sed s/\./\\./g 297s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 297s + apt list ?obsolete 297s + tail -n+2 297s + grep -v ^linux-.*6\.12\.0-15-generic.* 297s + cut -d/ -f1 298s + true 298s + obsolete_pkgs= 298s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 298s Reading package lists... 298s Building dependency tree... 298s Reading state information... 298s Solving dependencies... 298s The following packages will be REMOVED: 298s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 298s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 298s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 298s linux-tools-6.11.0-8-generic* 298s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 298s After this operation, 167 MB disk space will be freed. 298s (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 ... 81030 files and directories currently installed.) 298s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 298s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 298s Removing libpython3.12t64:s390x (3.12.9-1) ... 298s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 298s Removing libnsl2:s390x (1.3.0-3build3) ... 298s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 298s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 298s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 299s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 299s Processing triggers for libc-bin (2.40-4ubuntu1) ... 299s (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 ... 55930 files and directories currently installed.) 299s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 299s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 299s + grep -q trusty /etc/lsb-release 299s + [ ! -d /usr/share/doc/unattended-upgrades ] 299s + [ ! -d /usr/share/doc/lxd ] 299s + [ ! -d /usr/share/doc/lxd-client ] 299s + [ ! -d /usr/share/doc/snapd ] 299s + type iptables 299s + cat 299s + chmod 755 /etc/rc.local 299s + . /etc/rc.local 299s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 299s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 299s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 299s + uname -m 299s + [ s390x = ppc64le ] 299s + [ -d /run/systemd/system ] 299s + systemd-detect-virt --quiet --vm 299s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 299s + cat 299s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 299s + echo COMPRESS=lz4 299s autopkgtest [20:38:15]: upgrading testbed (apt dist-upgrade and autopurge) 299s Reading package lists... 300s Building dependency tree... 300s Reading state information... 300s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 300s Starting 2 pkgProblemResolver with broken count: 0 300s Done 300s Entering ResolveByKeep 300s 300s Calculating upgrade... 300s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 300s Reading package lists... 300s Building dependency tree... 300s Reading state information... 301s Starting pkgProblemResolver with broken count: 0 301s Starting 2 pkgProblemResolver with broken count: 0 301s Done 301s Solving dependencies... 301s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 301s autopkgtest [20:38:17]: rebooting testbed after setup commands that affected boot 321s Reading package lists... 321s Building dependency tree... 321s Reading state information... 321s Starting pkgProblemResolver with broken count: 0 321s Starting 2 pkgProblemResolver with broken count: 0 321s Done 322s The following NEW packages will be installed: 322s gsasl libgsasl18 libgssglue1 libidn12 libntlm0 322s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 322s Need to get 221 kB of archives. 322s After this operation, 659 kB of additional disk space will be used. 322s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libgssglue1 s390x 0.9-1build1 [20.6 kB] 322s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libidn12 s390x 1.42-3 [56.9 kB] 322s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libntlm0 s390x 1.8-3 [20.6 kB] 322s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libgsasl18 s390x 2.2.1-1willsync1ubuntu1 [85.8 kB] 322s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x gsasl s390x 2.2.1-1willsync1ubuntu1 [36.8 kB] 322s Fetched 221 kB in 0s (513 kB/s) 322s Selecting previously unselected package libgssglue1:s390x. 322s (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 ... 55928 files and directories currently installed.) 322s Preparing to unpack .../libgssglue1_0.9-1build1_s390x.deb ... 322s Unpacking libgssglue1:s390x (0.9-1build1) ... 322s Selecting previously unselected package libidn12:s390x. 322s Preparing to unpack .../libidn12_1.42-3_s390x.deb ... 322s Unpacking libidn12:s390x (1.42-3) ... 322s Selecting previously unselected package libntlm0:s390x. 322s Preparing to unpack .../libntlm0_1.8-3_s390x.deb ... 322s Unpacking libntlm0:s390x (1.8-3) ... 322s Selecting previously unselected package libgsasl18:s390x. 322s Preparing to unpack .../libgsasl18_2.2.1-1willsync1ubuntu1_s390x.deb ... 322s Unpacking libgsasl18:s390x (2.2.1-1willsync1ubuntu1) ... 322s Selecting previously unselected package gsasl. 322s Preparing to unpack .../gsasl_2.2.1-1willsync1ubuntu1_s390x.deb ... 322s Unpacking gsasl (2.2.1-1willsync1ubuntu1) ... 322s Setting up libntlm0:s390x (1.8-3) ... 322s Setting up libidn12:s390x (1.42-3) ... 322s Setting up libgssglue1:s390x (0.9-1build1) ... 322s Setting up libgsasl18:s390x (2.2.1-1willsync1ubuntu1) ... 322s Setting up gsasl (2.2.1-1willsync1ubuntu1) ... 322s Processing triggers for man-db (2.13.0-1) ... 323s Processing triggers for libc-bin (2.40-4ubuntu1) ... 325s autopkgtest [20:38:41]: test bad-configs: [----------------------- 325s + export LC_ALL=C 325s + : /usr/bin/gsasl 325s + CMD=/usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 325s + /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 325s + grep gss_init_sec_context 325s /usr/bin/gsasl: mechanism error: GSSAPI error in client while negotiating security context in gss_init_sec_context() in SASL library. This is most likely due insufficient credentials or malicious interactions. 325s can't open libfoobarbaz.so.3: libfoobarbaz.so.3: cannot open shared object file: No such file or directory 325s + /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 325s + grep -e ^warning -e ^can't open 325s + echo libfoobarbaz.so.3 mechglue_internal_krb5_init 325s + GSSAPI_MECH_CONF=my_gssapi_mech.conf /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 325s + grep ^can't open libfoobarbaz.so.3 325s + GSSAPI_MECH_CONF=my_gssapi_mech.conf /usr/bin/gsasl --client-mechanisms 325s + grep ^can't open libfoobarbaz.so.3 325s + rm -f my_gssapi_mech.conf 325s + exit 0 325s autopkgtest [20:38:41]: test bad-configs: -----------------------] 326s bad-configs PASS 326s autopkgtest [20:38:42]: test bad-configs: - - - - - - - - - - results - - - - - - - - - - 326s autopkgtest [20:38:42]: test bad-configs-root: preparing testbed 327s Reading package lists... 327s Building dependency tree... 327s Reading state information... 327s Starting pkgProblemResolver with broken count: 0 327s Starting 2 pkgProblemResolver with broken count: 0 327s Done 327s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 328s autopkgtest [20:38:44]: test bad-configs-root: [----------------------- 328s /usr/bin/gsasl: mechanism error: GSSAPI error in client while negotiating security context in gss_init_sec_context() in SASL library. This is most likely due insufficient credentials or malicious interactions. 328s + export LC_ALL=C 328s + : /usr/bin/gsasl 328s + CMD=/usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 328s + /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 328s + grep gss_init_sec_context 328s + /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 328s + grep -e ^warning -e ^can't open 328s + cp /etc/gssapi_mech.conf /etc/gssapi_mech.conf-backup 328s + echo libbarfoobaz.so.3 mechglue_internal_krb5_init 328s + /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 328s + grep ^can't open libbarfoobaz.so.3 328s can't open libbarfoobaz.so.3: libbarfoobaz.so.3: cannot open shared object file: No such file or directory 328s + /usr/bin/gsasl --client-mechanisms+ 328s grep ^can't open libbarfoobaz.so.3 328s + echo libfoobarbaz.so.3 mechglue_internal_krb5_init 328s + GSSAPI_MECH_CONF=my_gssapi_mech.conf /usr/bin/gsasl --service imap --hostname foobar --client -m GSSAPI 328s + grep ^can't open libfoobarbaz.so.3 328s can't open libfoobarbaz.so.3: libfoobarbaz.so.3: cannot open shared object file: No such file or directory 328s + + grep ^can't open libfoobarbaz.so.3 328s GSSAPI_MECH_CONF=my_gssapi_mech.conf /usr/bin/gsasl --client-mechanisms 328s + mv /etc/gssapi_mech.conf-backup /etc/gssapi_mech.conf 328s + rm -f my_gssapi_mech.conf 328s + exit 0 328s autopkgtest [20:38:44]: test bad-configs-root: -----------------------] 329s bad-configs-root PASS 329s autopkgtest [20:38:45]: test bad-configs-root: - - - - - - - - - - results - - - - - - - - - - 329s autopkgtest [20:38:45]: test gsasl-dovecot-gssapi: preparing testbed 329s Reading package lists... 329s Building dependency tree... 329s Reading state information... 330s Starting pkgProblemResolver with broken count: 0 330s Starting 2 pkgProblemResolver with broken count: 0 330s Done 330s The following NEW packages will be installed: 330s dovecot-core dovecot-gssapi dovecot-imapd krb5-admin-server krb5-config 330s krb5-kdc krb5-user libc6-dbg libevent-2.1-7t64 libexttextcat-2.0-0 330s libexttextcat-data libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 330s libkdb5-10t64 liblua5.4-0 libsodium23 libstemmer0d libverto-libevent1t64 330s libverto1t64 ssl-cert valgrind valgrind-if-available 330s 0 upgraded, 23 newly installed, 0 to remove and 0 not upgraded. 330s Need to get 22.5 MB of archives. 330s After this operation, 86.9 MB of additional disk space will be used. 330s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 330s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libexttextcat-data all 3.4.7-1build1 [193 kB] 330s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libexttextcat-2.0-0 s390x 3.4.7-1build1 [14.1 kB] 330s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x liblua5.4-0 s390x 5.4.7-1 [174 kB] 330s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libsodium23 s390x 1.0.18-1build3 [138 kB] 330s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libstemmer0d s390x 2.2.0-4build1 [174 kB] 330s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x dovecot-core s390x 1:2.3.21.1+dfsg1-1ubuntu2 [3363 kB] 331s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 331s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu1 [59.1 kB] 331s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu1 [40.5 kB] 331s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu1 [41.9 kB] 331s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu1 [55.6 kB] 331s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-4ubuntu1 [110 kB] 331s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 331s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 331s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 331s Get:17 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-4ubuntu1 [198 kB] 331s Get:18 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu1 [95.7 kB] 331s Get:19 http://ftpmaster.internal/ubuntu plucky/universe s390x dovecot-gssapi s390x 1:2.3.21.1+dfsg1-1ubuntu2 [11.9 kB] 331s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x dovecot-imapd s390x 1:2.3.21.1+dfsg1-1ubuntu2 [192 kB] 331s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libc6-dbg s390x 2.40-4ubuntu1 [7794 kB] 332s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x valgrind s390x 1:3.24.0-0ubuntu1 [9636 kB] 332s Get:23 http://ftpmaster.internal/ubuntu plucky/universe s390x valgrind-if-available s390x 3.18.1-1-1ubuntu1 [2150 B] 332s Preconfiguring packages ... 332s Fetched 22.5 MB in 2s (9351 kB/s) 332s Selecting previously unselected package ssl-cert. 333s (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 ... 55962 files and directories currently installed.) 333s Preparing to unpack .../00-ssl-cert_1.1.3ubuntu1_all.deb ... 333s Unpacking ssl-cert (1.1.3ubuntu1) ... 333s Selecting previously unselected package libexttextcat-data. 333s Preparing to unpack .../01-libexttextcat-data_3.4.7-1build1_all.deb ... 333s Unpacking libexttextcat-data (3.4.7-1build1) ... 333s Selecting previously unselected package libexttextcat-2.0-0:s390x. 333s Preparing to unpack .../02-libexttextcat-2.0-0_3.4.7-1build1_s390x.deb ... 333s Unpacking libexttextcat-2.0-0:s390x (3.4.7-1build1) ... 333s Selecting previously unselected package liblua5.4-0:s390x. 333s Preparing to unpack .../03-liblua5.4-0_5.4.7-1_s390x.deb ... 333s Unpacking liblua5.4-0:s390x (5.4.7-1) ... 333s Selecting previously unselected package libsodium23:s390x. 333s Preparing to unpack .../04-libsodium23_1.0.18-1build3_s390x.deb ... 333s Unpacking libsodium23:s390x (1.0.18-1build3) ... 333s Selecting previously unselected package libstemmer0d:s390x. 333s Preparing to unpack .../05-libstemmer0d_2.2.0-4build1_s390x.deb ... 333s Unpacking libstemmer0d:s390x (2.2.0-4build1) ... 333s Selecting previously unselected package dovecot-core. 333s Preparing to unpack .../06-dovecot-core_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 333s Unpacking dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 333s Selecting previously unselected package krb5-config. 333s Preparing to unpack .../07-krb5-config_2.7_all.deb ... 333s Unpacking krb5-config (2.7) ... 333s Selecting previously unselected package libgssrpc4t64:s390x. 333s Preparing to unpack .../08-libgssrpc4t64_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package libkadm5clnt-mit12:s390x. 333s Preparing to unpack .../09-libkadm5clnt-mit12_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package libkdb5-10t64:s390x. 333s Preparing to unpack .../10-libkdb5-10t64_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package libkadm5srv-mit12:s390x. 333s Preparing to unpack .../11-libkadm5srv-mit12_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package krb5-user. 333s Preparing to unpack .../12-krb5-user_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking krb5-user (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package libevent-2.1-7t64:s390x. 333s Preparing to unpack .../13-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 333s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 333s Selecting previously unselected package libverto1t64:s390x. 333s Preparing to unpack .../14-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 333s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 333s Selecting previously unselected package libverto-libevent1t64:s390x. 333s Preparing to unpack .../15-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 333s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 333s Selecting previously unselected package krb5-kdc. 333s Preparing to unpack .../16-krb5-kdc_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package krb5-admin-server. 333s Preparing to unpack .../17-krb5-admin-server_1.21.3-4ubuntu1_s390x.deb ... 333s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 333s Selecting previously unselected package dovecot-gssapi. 333s Preparing to unpack .../18-dovecot-gssapi_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 333s Unpacking dovecot-gssapi (1:2.3.21.1+dfsg1-1ubuntu2) ... 333s Selecting previously unselected package dovecot-imapd. 333s Preparing to unpack .../19-dovecot-imapd_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 333s Unpacking dovecot-imapd (1:2.3.21.1+dfsg1-1ubuntu2) ... 333s Selecting previously unselected package libc6-dbg:s390x. 333s Preparing to unpack .../20-libc6-dbg_2.40-4ubuntu1_s390x.deb ... 333s Unpacking libc6-dbg:s390x (2.40-4ubuntu1) ... 333s Selecting previously unselected package valgrind. 333s Preparing to unpack .../21-valgrind_1%3a3.24.0-0ubuntu1_s390x.deb ... 333s Unpacking valgrind (1:3.24.0-0ubuntu1) ... 333s Selecting previously unselected package valgrind-if-available. 333s Preparing to unpack .../22-valgrind-if-available_3.18.1-1-1ubuntu1_s390x.deb ... 333s Unpacking valgrind-if-available (3.18.1-1-1ubuntu1) ... 333s Setting up libsodium23:s390x (1.0.18-1build3) ... 333s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 333s Setting up libc6-dbg:s390x (2.40-4ubuntu1) ... 333s Setting up ssl-cert (1.1.3ubuntu1) ... 333s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 334s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 334s Setting up krb5-config (2.7) ... 334s Setting up libexttextcat-data (3.4.7-1build1) ... 334s Setting up liblua5.4-0:s390x (5.4.7-1) ... 334s Setting up libstemmer0d:s390x (2.2.0-4build1) ... 334s Setting up valgrind (1:3.24.0-0ubuntu1) ... 334s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 334s Setting up valgrind-if-available (3.18.1-1-1ubuntu1) ... 334s Setting up libexttextcat-2.0-0:s390x (3.4.7-1build1) ... 334s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 334s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 334s Setting up dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 334s Creating config file /etc/dovecot/dovecot.conf with new version 334s Creating config file /etc/dovecot/dovecot-dict-auth.conf.ext with new version 334s Creating config file /etc/dovecot/dovecot-dict-sql.conf.ext with new version 334s Creating config file /etc/dovecot/dovecot-sql.conf.ext with new version 334s Creating config file /etc/dovecot/conf.d/10-auth.conf with new version 334s Creating config file /etc/dovecot/conf.d/10-director.conf with new version 335s Creating config file /etc/dovecot/conf.d/10-logging.conf with new version 335s Creating config file /etc/dovecot/conf.d/10-mail.conf with new version 335s Creating config file /etc/dovecot/conf.d/10-master.conf with new version 335s Creating config file /etc/dovecot/conf.d/10-ssl.conf with new version 335s Creating config file /etc/dovecot/conf.d/10-tcpwrapper.conf with new version 335s Creating config file /etc/dovecot/conf.d/15-lda.conf with new version 335s Creating config file /etc/dovecot/conf.d/15-mailboxes.conf with new version 335s Creating config file /etc/dovecot/conf.d/90-acl.conf with new version 336s Creating config file /etc/dovecot/conf.d/90-plugin.conf with new version 336s Creating config file /etc/dovecot/conf.d/90-quota.conf with new version 336s Creating config file /etc/dovecot/conf.d/auth-checkpassword.conf.ext with new version 336s Creating config file /etc/dovecot/conf.d/auth-deny.conf.ext with new version 336s Creating config file /etc/dovecot/conf.d/auth-dict.conf.ext with new version 336s Creating config file /etc/dovecot/conf.d/auth-master.conf.ext with new version 336s Creating config file /etc/dovecot/conf.d/auth-passwdfile.conf.ext with new version 336s Creating config file /etc/dovecot/conf.d/auth-sql.conf.ext with new version 337s Creating config file /etc/dovecot/conf.d/auth-static.conf.ext with new version 337s Creating config file /etc/dovecot/conf.d/auth-system.conf.ext with new version 337s Created symlink '/etc/systemd/system/multi-user.target.wants/dovecot.service' → '/usr/lib/systemd/system/dovecot.service'. 337s dovecot.socket is a disabled or a static unit, not starting it. 337s Setting up dovecot-imapd (1:2.3.21.1+dfsg1-1ubuntu2) ... 338s Creating config file /etc/dovecot/conf.d/20-imap.conf with new version 338s Setting up dovecot-gssapi (1:2.3.21.1+dfsg1-1ubuntu2) ... 338s Setting up krb5-user (1.21.3-4ubuntu1) ... 338s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 338s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 338s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 338s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 338s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 338s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 338s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 338s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 338s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 338s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 338s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 338s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 338s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 338s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 339s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 339s Processing triggers for libc-bin (2.40-4ubuntu1) ... 339s Processing triggers for ufw (0.36.2-9) ... 339s Processing triggers for man-db (2.13.0-1) ... 340s Processing triggers for dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 342s autopkgtest [20:38:58]: test gsasl-dovecot-gssapi: [----------------------- 342s /usr/bin/valgrind 342s + command -v valgrind 342s + VALGRIND=valgrind --error-exitcode=1 342s + GSASL=valgrind --error-exitcode=1 /usr/bin/gsasl debian/tests/gsasl-dovecot-gssapi.sh 342s + : valgrind --error-exitcode=1 /usr/bin/gsasl 342s + valgrind --error-exitcode=1 /usr/bin/gsasl --version 342s + grep ^gsasl (GNU SASL 342s gsasl (GNU SASL) 2.2.1 342s + + grep GSSAPI 342s valgrind --error-exitcode=1 /usr/bin/gsasl --client-mechanisms 343s ANONYMOUS EXTERNAL LOGIN PLAIN SECURID NTLM DIGEST-MD5 CRAM-MD5 SCRAM-SHA-1 SCRAM-SHA-1-PLUS SCRAM-SHA-256 SCRAM-SHA-256-PLUS SAML20 OPENID20 GSSAPI GS2-KRB5 343s + test no = yes 343s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 343s + command -v ss 343s + command -v id 343s + command -v hostname 343s + command -v dovecot 343s + command -v kinit 343s + command -v kdb5_util 343s + command -v kadmin.local 343s + command -v krb5kdc 343s + mktemp -d 343s /usr/bin/ss 343s /usr/bin/id 343s /usr/bin/hostname 343s /usr/sbin/dovecot 343s /usr/bin/kinit 343s /usr/sbin/kdb5_util 343s /usr/sbin/kadmin.local 343s /usr/sbin/krb5kdc 343s + WORKDIR=/tmp/tmp.UDdeVmHnYv 343s + trap set +e; test -f $WORKDIR/pid && kill `cat $WORKDIR/pid`; dovecot -c $WORKDIR/d/dovecot.conf stop; tail -v -n +0 $WORKDIR/out-* $WORKDIR/dovecot.log $WORKDIR/kdc.log; rm -rfv $WORKDIR 0 INT QUIT ABRT PIPE TERM 343s + : ubuntu 343s + id -gn 343s + : ubuntu 343s + mkdir /tmp/tmp.UDdeVmHnYv/k /tmp/tmp.UDdeVmHnYv/d 343s + cat 343s + Initializing database '/tmp/tmp.UDdeVmHnYv/principal' for realm 'GSASL.EXAMPLE', 343s master key name 'K/M@GSASL.EXAMPLE' 343s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.UDdeVmHnYv/d/dovecot.keytab. 343s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.UDdeVmHnYv/d/dovecot.keytab. 343s hostname -d 343s + hostname -f 343s + cat 343s + hostname -f 343s + cat 343s + test ubuntu = root 343s + cat 343s + export KRB5CCNAME=/tmp/tmp.UDdeVmHnYv/cc 343s + export KRB5_CONFIG=/tmp/tmp.UDdeVmHnYv/k/krb5.conf 343s + export KRB5_KDC_PROFILE=/tmp/tmp.UDdeVmHnYv/k 343s + kdb5_util -P foo create -s 343s + hostname -f 343s + kadmin.local addprinc -randkey imap/autopkgtest.local 343s + kadmin.local addprinc -pw bar ubuntu 343s + hostname -f 343s + kadmin.local ktadd -k /tmp/tmp.UDdeVmHnYv/d/dovecot.keytab imap/autopkgtest.local 343s + i=0 343s + krb5kdc -n -P /tmp/tmp.UDdeVmHnYv/pid 343s + ss -na 343s + grep 0.0.0.0:17643 343s + grep LISTEN 343s krb5kdc: starting... 343s + dovecot -c /tmp/tmp.UDdeVmHnYv/d/dovecot.conf 343s tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 343s + hostname -f 343s + valgrind --error-exitcode=1 /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 344s + grep -q gss_init_sec_context /tmp/tmp.UDdeVmHnYv/out-err 344s + echo bar 344s + kinit ubuntu 345s Password for ubuntu@GSASL.EXAMPLE: 345s + hostname -f 345s + valgrind --error-exitcode=1 /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 346s + set +e 346s + test -f /tmp/tmp.UDdeVmHnYv/pid 346s + cat /tmp/tmp.UDdeVmHnYv/pid 346s + kill 4932 346s + dovecot -c /tmp/tmp.UDdeVmHnYv/d/dovecot.conf stop 347s + tail -v -n +0 /tmp/tmp.UDdeVmHnYv/out-err /tmp/tmp.UDdeVmHnYv/out-ok /tmp/tmp.UDdeVmHnYv/dovecot.log /tmp/tmp.UDdeVmHnYv/kdc.log 347s ==> /tmp/tmp.UDdeVmHnYv/out-err <== 347s ==4955== Memcheck, a memory error detector 347s ==4955== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 347s ==4955== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 347s ==4955== Command: /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 347s ==4955== 347s Trying ‘autopkgtest.local’... 347s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 347s . CAPABILITY 347s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 347s . OK Pre-login capabilities listed, post-login capabilities have more. 347s . AUTHENTICATE GSSAPI 347s + 347s /usr/bin/gsasl: mechanism error: GSSAPI error in client while negotiating security context in gss_init_sec_context() in SASL library. This is most likely due insufficient credentials or malicious interactions. 347s ==4955== 347s ==4955== HEAP SUMMARY: 347s ==4955== in use at exit: 16,581 bytes in 74 blocks 347s ==4955== total heap usage: 2,048 allocs, 1,974 frees, 315,713 bytes allocated 347s ==4955== 347s ==4955== LEAK SUMMARY: 347s ==4955== definitely lost: 16 bytes in 1 blocks 347s ==4955== indirectly lost: 0 bytes in 0 blocks 347s ==4955== possibly lost: 0 bytes in 0 blocks 347s ==4955== still reachable: 16,565 bytes in 73 blocks 347s ==4955== suppressed: 0 bytes in 0 blocks 347s ==4955== Rerun with --leak-check=full to see details of leaked memory 347s ==4955== 347s ==4955== For lists of detected and suppressed errors, rerun with: -s 347s ==4955== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 347s 347s ==> /tmp/tmp.UDdeVmHnYv/out-ok <== 347s ==4964== Memcheck, a memory error detector 347s ==4964== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 347s ==4964== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 347s ==4964== Command: /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 347s ==4964== 347s Trying ‘autopkgtest.local’... 347s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 347s . CAPABILITY 347s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 347s . OK Pre-login capabilities listed, post-login capabilities have more. 347s . AUTHENTICATE GSSAPI 347s + 347s YIIC+gYJKoZIhvcSAQICAQBuggLpMIIC5aADAgEFoQMCAQ6iBwMFACAAAACjggH6YYIB9jCCAfKgAwIBBaEPGw1HU0FTTC5FWEFNUExFoiQwIqADAgEDoRswGRsEaW1hcBsRYXV0b3BrZ3Rlc3QubG9jYWyjggGyMIIBrqADAgESoQMCAQKiggGgBIIBnPO7K2j98k7pWTY7KcxYbEWhNB/wvAqoDmHItO2YFD/q+aLyGWpi9PyFBmAFohCDkU+Nut5nA1ymt0JiP1gCW5zhJNSyDbbt3MU4IME/WVstzYvipUKUGu2OnYXxRDIJmetSiqlXZmIB24fFjCOX4Qhkr5YE7J3GTkrvcoMqejWaw81ceyMUnnZMCTUwLJ+9SIfaqVzkIkuPwz5NsUeMHG84gjZEsZS6IhQHzNiWS2jKaiTvbMNXb+TaNqglUZGdZaXH6rp/bwdej81BSa5JCQZ7TGsYNhNQh5HJjzK8s4UJqdVyqYBfE8tW8oD/RMK7Oudzr0M28trb3yQz8SQl5tZGpQdQXsGKMyZiuLv1py4LMtc+VgZzwGOT7mELul2EM2B68r0sx/3i4124MbFjX4661/m5yMzRjSWsRl17AWUuATUjPul36Dy8g3AFSV0SRGFiBn22xZ5FraSFkcfQ/r3tc35fBbrMIVA/UZkXCLH7uuWbarApjYrYm31BkyUCBJsW0tHbpZgEJaf9cKdNijaNxiowEZGx19ZIPG2kgdEwgc6gAwIBEqKBxgSBw2wYIAJ/e+5CEfKcDRqw3UT7RPz5o0kq6J9LtSED6ppd2oua2Icx26V+VGNoAM/wEK8gMBtVJtbfE5S2Pq7TcC+d4VsMRURpIabGhjUs6QpFOY5+EiRod7efkXronqr80lF8ABvjmfeDQYY0d30wXXfKWggqKPDtYVy5QLNF3CaEhpd3t7Ip5f1FkZ4PYvdHxVDjQ7BJemPuklLe5g7+UQLFOfGJDe9jjNfC7jdhBUsfM/jXPwfWIGk/X1Qkz66ow/7CXA== 347s + YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvHTvypc9SpyJT0zI40Jy7rZ6GQUxnhc5DaYsuDGHnEGidc11ZtRyQhKg9MJE0ZkZa0yF8LbzV9vneZTiiyqfHRwYhu07gCuaz8OWhJ3BCsVg0+Svvl076wNDvC3jX1zJNs9FeyAqpiQBSZ43Cr+Zl 347s 347s + BQQF/wAMAAAAAAAAGoRuZAH////+Nlu2HmBNSBsr/XA= 347s BQQE/wAMAAAAAAAAGEJMmAH///8waUQ2Br2s8CVmDSg= 347s ==4964== 347s ==4964== HEAP SUMMARY: 347s ==4964== in use at exit: 21,729 bytes in 165 blocks 347s ==4964== total heap usage: 3,173 allocs, 3,008 frees, 575,152 bytes allocated 347s ==4964== 347s ==4964== LEAK SUMMARY: 347s ==4964== definitely lost: 384 bytes in 3 blocks 347s ==4964== indirectly lost: 8,466 bytes in 85 blocks 347s ==4964== possibly lost: 0 bytes in 0 blocks 347s ==4964== still reachable: 12,879 bytes in 77 blocks 347s ==4964== suppressed: 0 bytes in 0 blocks 347s ==4964== Rerun with --leak-check=full to see details of leaked memory 347s ==4964== 347s ==4964== For lists of detected and suppressed errors, rerun with: -s 347s ==4964== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 347s 347s ==> /tmp/tmp.UDdeVmHnYv/dovecot.log <== 347s Feb 21 20:40:25 master: Info: Dovecot v2.3.21.1 (d492236fa0) starting up for imap (core dumps disabled) 347s Feb 21 20:40:25 master: Error: file_dotlock_open(/var/lib/dovecot/instances) failed: Permission denied 347s Feb 21 20:40:26 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 347s Feb 21 20:40:26 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so 347s Feb 21 20:40:26 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 347s Feb 21 20:40:26 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libmech_gssapi.so 347s Feb 21 20:40:26 auth: Debug: Wrote new auth token secret to /tmp/tmp.UDdeVmHnYv/b/auth-token-secret.dat 347s Feb 21 20:40:26 auth: Debug: auth client connected (pid=4957) 347s Feb 21 20:40:26 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=sfwZAK0uEu5/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=60946 347s Feb 21 20:40:26 auth: Debug: gssapi(?,127.0.0.1,): Obtaining credentials for imap@autopkgtest.local 347s Feb 21 20:40:26 auth: Debug: client passdb out: CONT 1 347s Feb 21 20:40:26 imap-login: Info: Disconnected: Connection closed (client didn't finish SASL auth, waited 0 secs): user=<>, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, session= 347s Feb 21 20:40:26 auth: Debug: client in: CANCEL 1 347s Feb 21 20:40:27 auth: Debug: auth client connected (pid=4965) 347s Feb 21 20:39:01 log(4954): Warning: Time moved backwards by 85.652360 seconds. 347s Feb 21 20:39:01 imap-login: Warning: Time moved backwards by 85.659739 seconds. 347s Feb 21 20:39:01 auth: Warning: Time moved backwards by 85.651251 seconds. 347s Feb 21 20:39:01 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=VeoR+6wuIu5/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=60962 347s Feb 21 20:39:01 anvil: Warning: Time moved backwards by 85.651084 seconds. 347s Feb 21 20:39:01 auth: Debug: gssapi(?,127.0.0.1,): Obtaining credentials for imap@autopkgtest.local 347s Feb 21 20:39:01 auth: Debug: client passdb out: CONT 1 347s Feb 21 20:39:02 auth: Debug: client in: CONT 347s Feb 21 20:39:02 auth: Debug: gssapi(ubuntu@gsasl.example,127.0.0.1,): security context state completed. 347s Feb 21 20:39:02 auth: Debug: client passdb out: CONT 1 YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvHTvypc9SpyJT0zI40Jy7rZ6GQUxnhc5DaYsuDGHnEGidc11ZtRyQhKg9MJE0ZkZa0yF8LbzV9vneZTiiyqfHRwYhu07gCuaz8OWhJ3BCsVg0+Svvl076wNDvC3jX1zJNs9FeyAqpiQBSZ43Cr+Zl 347s Feb 21 20:39:02 auth: Debug: client in: CONT 347s Feb 21 20:39:02 auth: Debug: gssapi(ubuntu@gsasl.example,127.0.0.1,): Negotiated security layer 347s Feb 21 20:39:02 auth: Debug: client passdb out: CONT 1 BQQF/wAMAAAAAAAAGoRuZAH////+Nlu2HmBNSBsr/XA= 347s Feb 21 20:39:02 auth: Debug: client in: CONT 347s Feb 21 20:39:02 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Performing passdb lookup 347s Feb 21 20:39:02 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): lookup 347s Feb 21 20:39:02 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Finished passdb lookup 347s Feb 21 20:39:02 auth: Debug: auth(ubuntu@gsasl.example,127.0.0.1,): Auth request finished 347s Feb 21 20:39:02 master: Warning: Time moved backwards by 84.920954 seconds, waiting for 84.920 seconds until new services are launched again. 347s Feb 21 20:39:02 auth: Debug: client passdb out: OK 1 user=ubuntu@gsasl.example original_user=ubuntu@GSASL.EXAMPLE 347s Feb 21 20:39:02 imap-login: Error: master(imap): fd_send(fd=17) failed: Broken pipe (client-pid=4965, client-id=1, rip=127.0.0.1, created 0 msecs ago, received 0/4 bytes) 347s Feb 21 20:39:02 auth: Debug: client in: CANCEL 1 347s Feb 21 20:39:02 imap-login: Info: Disconnected: Internal login failure (pid=4965 id=1): user=, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, session= 347s Feb 21 20:39:02 stats: Warning: Time moved backwards by 84.920564 seconds. 347s Feb 21 20:39:02 config: Warning: Time moved backwards by 84.919974 seconds. 347s Feb 21 20:39:02 master: Warning: Killed with signal 15 (by pid=4969 uid=1000 code=kill) 347s Feb 21 20:39:02 master: Error: file_dotlock_open(/var/lib/dovecot/instances) failed: Permission denied 347s 347s ==> /tmp/tmp.UDdeVmHnYv/kdc.log <== 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](Error): preauth spake failed to initialize: No SPAKE preauth groups configured 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](info): setting up network... 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](info): setsockopt(10,IPV6_V6ONLY,1) worked 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](info): setsockopt(12,IPV6_V6ONLY,1) worked 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](info): set up 4 sockets 347s Feb 21 20:40:25 autopkgtest krb5kdc[4932](info): commencing operation 347s Feb 21 20:40:26 autopkgtest krb5kdc[4932](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: NEEDED_PREAUTH: ubuntu@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE, Additional pre-authentication required 347s Feb 21 20:40:26 autopkgtest krb5kdc[4932](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: ISSUE: authtime 1740170426, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, ubuntu@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE 347s Feb 21 20:39:02 autopkgtest krb5kdc[4932](info): TGS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: ISSUE: authtime 1740170426, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, ubuntu@GSASL.EXAMPLE for imap/autopkgtest.local@GSASL.EXAMPLE 347s Feb 21 20:39:02 autopkgtest krb5kdc[4932](info): shutting down 347s removed '/tmp/tmp.UDdeVmHnYv/pid' 347s removed '/tmp/tmp.UDdeVmHnYv/d/dovecot.keytab' 347s removed '/tmp/tmp.UDdeVmHnYv/d/dovecot.conf' 347s removed directory '/tmp/tmp.UDdeVmHnYv/d' 347s removed '/tmp/tmp.UDdeVmHnYv/stash' 347s removed '/tmp/tmp.UDdeVmHnYv/kdc.log' 347s removed '/tmp/tmp.UDdeVmHnYv/dovecot.log' 347s removed '/tmp/tmp.UDdeVmHnYv/principal.ok' 347s removed '/tmp/tmp.UDdeVmHnYv/k/kdc.conf' 347s removed '/tmp/tmp.UDdeVmHnYv/k/krb5.conf' 347s removed directory '/tmp/tmp.UDdeVmHnYv/k' 347s removed '/tmp/tmp.UDdeVmHnYv/b/imap-urlauth-worker' 347s removed '/tmp/tmp.UDdeVmHnYv/b/old-stats-user' 347s removed '/tmp/tmp.UDdeVmHnYv/b/anvil-auth-penalty' 347s removed directory '/tmp/tmp.UDdeVmHnYv/b/empty' 347s removed '/tmp/tmp.UDdeVmHnYv/b/indexer-worker' 347s removed '/tmp/tmp.UDdeVmHnYv/b/master' 347s removed '/tmp/tmp.UDdeVmHnYv/b/dict-async' 347s removed '/tmp/tmp.UDdeVmHnYv/b/log-errors' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-userdb' 347s removed '/tmp/tmp.UDdeVmHnYv/b/indexer' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-login' 347s removed '/tmp/tmp.UDdeVmHnYv/b/config' 347s removed '/tmp/tmp.UDdeVmHnYv/b/login/ipc-proxy' 347s removed '/tmp/tmp.UDdeVmHnYv/b/login/imap' 347s removed '/tmp/tmp.UDdeVmHnYv/b/login/login' 347s removed '/tmp/tmp.UDdeVmHnYv/b/login/dns-client' 347s removed '/tmp/tmp.UDdeVmHnYv/b/login/stats-writer' 347s removed directory '/tmp/tmp.UDdeVmHnYv/b/login' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-client' 347s removed '/tmp/tmp.UDdeVmHnYv/b/dns-client' 347s removed '/tmp/tmp.UDdeVmHnYv/b/old-stats-mail' 347s removed '/tmp/tmp.UDdeVmHnYv/b/old-stats' 347s removed '/tmp/tmp.UDdeVmHnYv/b/stats-reader' 347s removed '/tmp/tmp.UDdeVmHnYv/b/dict' 347s removed '/tmp/tmp.UDdeVmHnYv/b/doveadm-server' 347s removed '/tmp/tmp.UDdeVmHnYv/b/anvil' 347s removed '/tmp/tmp.UDdeVmHnYv/b/director-admin' 347s removed '/tmp/tmp.UDdeVmHnYv/b/stats-writer' 347s removed '/tmp/tmp.UDdeVmHnYv/b/imap-urlauth' 347s removed '/tmp/tmp.UDdeVmHnYv/b/imap-master' 347s removed '/tmp/tmp.UDdeVmHnYv/b/replication-notify-fifo' 347s removed '/tmp/tmp.UDdeVmHnYv/b/ipc' 347s removed '/tmp/tmp.UDdeVmHnYv/b/token-login/imap-urlauth' 347s removed '/tmp/tmp.UDdeVmHnYv/b/token-login/tokenlogin' 347s removed directory '/tmp/tmp.UDdeVmHnYv/b/token-login' 347s removed '/tmp/tmp.UDdeVmHnYv/b/dovecot.conf' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-worker' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-token-secret.dat' 347s removed '/tmp/tmp.UDdeVmHnYv/b/replicator' 347s removed '/tmp/tmp.UDdeVmHnYv/b/imap-hibernate' 347s removed '/tmp/tmp.UDdeVmHnYv/b/auth-master' 347s removed '/tmp/tmp.UDdeVmHnYv/b/replication-notify' 347s removed directory '/tmp/tmp.UDdeVmHnYv/b' 347s removed '/tmp/tmp.UDdeVmHnYv/principal.kadm5' 347s removed '/tmp/tmp.UDdeVmHnYv/out-ok' 347s removed '/tmp/tmp.UDdeVmHnYv/out-err' 347s removed '/tmp/tmp.UDdeVmHnYv/principal.kadm5.lock' 347s removed '/tmp/tmp.UDdeVmHnYv/principal' 347s removed '/tmp/tmp.UDdeVmHnYv/cc' 347s removed directory '/tmp/tmp.UDdeVmHnYv' 347s + rm -rfv /tmp/tmp.UDdeVmHnYv 348s autopkgtest [20:39:04]: test gsasl-dovecot-gssapi: -----------------------] 348s autopkgtest [20:39:04]: test gsasl-dovecot-gssapi: - - - - - - - - - - results - - - - - - - - - - 348s gsasl-dovecot-gssapi FAIL non-zero exit status 1 348s autopkgtest [20:39:04]: test gsasl-dovecot-gssapi-heimdal: preparing testbed 476s autopkgtest [20:41:12]: testbed dpkg architecture: s390x 476s autopkgtest [20:41:12]: testbed apt version: 2.9.30ubuntu1 476s autopkgtest [20:41:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 476s autopkgtest [20:41:12]: testbed release detected to be: plucky 477s autopkgtest [20:41:13]: updating testbed package index (apt update) 478s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 478s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 478s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 478s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 478s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [85.6 kB] 478s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.8 kB] 478s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [708 kB] 478s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 478s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [129 kB] 478s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [760 B] 478s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [633 kB] 478s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4436 B] 478s Fetched 1689 kB in 1s (1732 kB/s) 479s Reading package lists... 480s + lsb_release --codename --short 480s + RELEASE=plucky 480s + cat 480s + [ plucky != trusty ] 480s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 480s Reading package lists... 480s Building dependency tree... 480s Reading state information... 480s Calculating upgrade... 480s Calculating upgrade... 480s The following packages were automatically installed and are no longer required: 480s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 480s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 480s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 480s linux-tools-6.11.0-8-generic 480s Use 'sudo apt autoremove' to remove them. 480s The following packages will be upgraded: 480s apparmor base-files gcc-14-base libapparmor1 libgnutls30t64 liblsof0 libnss3 480s lsof motd-news-config ucf 480s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 480s Need to get 3746 kB of archives. 480s After this operation, 7168 B of additional disk space will be used. 480s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x motd-news-config all 13.6ubuntu1 [5168 B] 480s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x base-files s390x 13.6ubuntu1 [75.4 kB] 480s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-17ubuntu3 [53.6 kB] 480s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libapparmor1 s390x 4.1.0~beta5-0ubuntu5 [54.3 kB] 480s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libgnutls30t64 s390x 3.8.9-2ubuntu2 [956 kB] 481s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ucf all 3.0050 [43.5 kB] 481s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x apparmor s390x 4.1.0~beta5-0ubuntu5 [758 kB] 481s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x lsof s390x 4.99.4+dfsg-2 [243 kB] 481s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x liblsof0 s390x 4.99.4+dfsg-2 [58.6 kB] 481s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libnss3 s390x 2:3.108-1ubuntu1 [1499 kB] 481s Preconfiguring packages ... 481s Fetched 3746 kB in 1s (5002 kB/s) 481s (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 ... 81030 files and directories currently installed.) 481s Preparing to unpack .../motd-news-config_13.6ubuntu1_all.deb ... 481s Unpacking motd-news-config (13.6ubuntu1) over (13.5ubuntu3) ... 481s Preparing to unpack .../base-files_13.6ubuntu1_s390x.deb ... 481s Unpacking base-files (13.6ubuntu1) over (13.5ubuntu3) ... 481s Setting up base-files (13.6ubuntu1) ... 481s Updating /root/.profile to current default. 482s motd-news.service is a disabled or a static unit not running, not starting it. 482s (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 ... 81030 files and directories currently installed.) 482s Preparing to unpack .../0-gcc-14-base_14.2.0-17ubuntu3_s390x.deb ... 482s Unpacking gcc-14-base:s390x (14.2.0-17ubuntu3) over (14.2.0-17ubuntu1) ... 482s Preparing to unpack .../1-libapparmor1_4.1.0~beta5-0ubuntu5_s390x.deb ... 482s Unpacking libapparmor1:s390x (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 482s Preparing to unpack .../2-libgnutls30t64_3.8.9-2ubuntu2_s390x.deb ... 482s Unpacking libgnutls30t64:s390x (3.8.9-2ubuntu2) over (3.8.9-2ubuntu1) ... 482s Preparing to unpack .../3-ucf_3.0050_all.deb ... 482s Unpacking ucf (3.0050) over (3.0049) ... 482s Preparing to unpack .../4-apparmor_4.1.0~beta5-0ubuntu5_s390x.deb ... 482s Unpacking apparmor (4.1.0~beta5-0ubuntu5) over (4.1.0~beta5-0ubuntu4) ... 482s Preparing to unpack .../5-lsof_4.99.4+dfsg-2_s390x.deb ... 482s Unpacking lsof (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 482s Preparing to unpack .../6-liblsof0_4.99.4+dfsg-2_s390x.deb ... 482s Unpacking liblsof0 (4.99.4+dfsg-2) over (4.99.4+dfsg-1) ... 482s Preparing to unpack .../7-libnss3_2%3a3.108-1ubuntu1_s390x.deb ... 482s Unpacking libnss3:s390x (2:3.108-1ubuntu1) over (2:3.107-1ubuntu1) ... 483s Setting up libgnutls30t64:s390x (3.8.9-2ubuntu2) ... 483s Setting up motd-news-config (13.6ubuntu1) ... 483s Setting up libapparmor1:s390x (4.1.0~beta5-0ubuntu5) ... 483s Setting up gcc-14-base:s390x (14.2.0-17ubuntu3) ... 483s Setting up liblsof0 (4.99.4+dfsg-2) ... 483s Setting up libnss3:s390x (2:3.108-1ubuntu1) ... 483s Setting up apparmor (4.1.0~beta5-0ubuntu5) ... 483s Installing new version of config file /etc/apparmor.d/fusermount3 ... 483s Reloading AppArmor profiles 485s Setting up ucf (3.0050) ... 485s Setting up lsof (4.99.4+dfsg-2) ... 485s Processing triggers for libc-bin (2.40-4ubuntu1) ... 485s Processing triggers for systemd (257.2-3ubuntu1) ... 485s Processing triggers for man-db (2.13.0-1) ... 486s Processing triggers for plymouth-theme-ubuntu-text (24.004.60-2ubuntu5) ... 486s Processing triggers for install-info (7.1.1-1) ... 486s Processing triggers for initramfs-tools (0.145ubuntu2) ... 486s update-initramfs: Generating /boot/initrd.img-6.12.0-15-generic 486s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 490s Using config file '/etc/zipl.conf' 490s Building bootmap in '/boot' 490s Adding IPL section 'ubuntu' (default) 490s Preparing boot device for LD-IPL: vda (0000). 490s Done. 490s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 490s + /usr/lib/apt/apt-helper analyze-pattern ?true 490s + uname -r 490s + sed s/\./\\./g 490s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 490s + apt list ?obsolete 490s + tail -n+2 490s + grep -v ^linux-.*6\.12\.0-15-generic.* 490s + cut -d/ -f1 490s + true 490s + obsolete_pkgs= 490s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 490s Reading package lists... 490s Building dependency tree... 490s Reading state information... 490s Solving dependencies... 490s The following packages will be REMOVED: 490s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 490s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 490s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 490s linux-tools-6.11.0-8-generic* 490s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 490s After this operation, 167 MB disk space will be freed. 491s (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 ... 81030 files and directories currently installed.) 491s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 491s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 491s Removing libpython3.12t64:s390x (3.12.9-1) ... 491s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 491s Removing libnsl2:s390x (1.3.0-3build3) ... 491s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 491s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 491s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 491s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 491s Processing triggers for libc-bin (2.40-4ubuntu1) ... 491s (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 ... 55930 files and directories currently installed.) 492s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 492s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 492s + grep -q trusty /etc/lsb-release 492s + [ ! -d /usr/share/doc/unattended-upgrades ] 492s + [ ! -d /usr/share/doc/lxd ] 492s + [ ! -d /usr/share/doc/lxd-client ] 492s + [ ! -d /usr/share/doc/snapd ] 492s + type iptables 492s + cat 492s + chmod 755 /etc/rc.local 492s + . /etc/rc.local 492s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 492s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 492s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 492s + uname -m 492s + [ s390x = ppc64le ] 492s + [ -d /run/systemd/system ] 492s + systemd-detect-virt --quiet --vm 492s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 492s + cat 492s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 492s + echo COMPRESS=lz4 492s autopkgtest [20:41:28]: upgrading testbed (apt dist-upgrade and autopurge) 492s Reading package lists... 492s Building dependency tree... 492s Reading state information... 492s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 492s Starting 2 pkgProblemResolver with broken count: 0 492s Done 492s Entering ResolveByKeep 492s 492s Calculating upgrade... 493s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 493s Reading package lists... 493s Building dependency tree... 493s Reading state information... 493s Starting pkgProblemResolver with broken count: 0 493s Starting 2 pkgProblemResolver with broken count: 0 493s Done 493s Solving dependencies... 493s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 493s autopkgtest [20:41:29]: rebooting testbed after setup commands that affected boot 517s Reading package lists... 517s Building dependency tree... 517s Reading state information... 517s Starting pkgProblemResolver with broken count: 0 517s Starting 2 pkgProblemResolver with broken count: 0 517s Done 517s The following NEW packages will be installed: 517s dovecot-core dovecot-gssapi dovecot-imapd gsasl krb5-admin-server 517s krb5-config krb5-kdc krb5-user libasn1-8t64-heimdal libc6-dbg 517s libevent-2.1-7t64 libexttextcat-2.0-0 libexttextcat-data libgsasl18 517s libgssapi3t64-heimdal libgssglue1 libgssrpc4t64 libhcrypto5t64-heimdal 517s libheimbase1t64-heimdal libheimntlm0t64-heimdal libhx509-5t64-heimdal 517s libidn12 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 517s libkrb5-26t64-heimdal liblua5.4-0 libntlm0 libroken19t64-heimdal libsodium23 517s libstemmer0d libverto-libevent1t64 libverto1t64 libwind0t64-heimdal ssl-cert 517s valgrind valgrind-if-available 517s 0 upgraded, 37 newly installed, 0 to remove and 0 not upgraded. 517s Need to get 23.6 MB of archives. 517s After this operation, 90.6 MB of additional disk space will be used. 517s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 517s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libexttextcat-data all 3.4.7-1build1 [193 kB] 517s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libexttextcat-2.0-0 s390x 3.4.7-1build1 [14.1 kB] 517s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x liblua5.4-0 s390x 5.4.7-1 [174 kB] 517s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libsodium23 s390x 1.0.18-1build3 [138 kB] 518s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libstemmer0d s390x 2.2.0-4build1 [174 kB] 518s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x dovecot-core s390x 1:2.3.21.1+dfsg1-1ubuntu2 [3363 kB] 518s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 518s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-4ubuntu1 [59.1 kB] 518s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-4ubuntu1 [40.5 kB] 518s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-4ubuntu1 [41.9 kB] 518s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-4ubuntu1 [55.6 kB] 518s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-4ubuntu1 [110 kB] 518s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 518s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 518s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 518s Get:17 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-4ubuntu1 [198 kB] 518s Get:18 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-4ubuntu1 [95.7 kB] 518s Get:19 http://ftpmaster.internal/ubuntu plucky/universe s390x dovecot-gssapi s390x 1:2.3.21.1+dfsg1-1ubuntu2 [11.9 kB] 518s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x dovecot-imapd s390x 1:2.3.21.1+dfsg1-1ubuntu2 [192 kB] 518s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libgssglue1 s390x 0.9-1build1 [20.6 kB] 518s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x libidn12 s390x 1.42-3 [56.9 kB] 518s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libntlm0 s390x 1.8-3 [20.6 kB] 518s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libgsasl18 s390x 2.2.1-1willsync1ubuntu1 [85.8 kB] 518s Get:25 http://ftpmaster.internal/ubuntu plucky/universe s390x gsasl s390x 2.2.1-1willsync1ubuntu1 [36.8 kB] 518s Get:26 http://ftpmaster.internal/ubuntu plucky/universe s390x libroken19t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [50.3 kB] 518s Get:27 http://ftpmaster.internal/ubuntu plucky/universe s390x libasn1-8t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [178 kB] 518s Get:28 http://ftpmaster.internal/ubuntu plucky/universe s390x libheimbase1t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [33.6 kB] 518s Get:29 http://ftpmaster.internal/ubuntu plucky/universe s390x libhcrypto5t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [100 kB] 518s Get:30 http://ftpmaster.internal/ubuntu plucky/universe s390x libwind0t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [59.7 kB] 518s Get:31 http://ftpmaster.internal/ubuntu plucky/universe s390x libhx509-5t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [114 kB] 518s Get:32 http://ftpmaster.internal/ubuntu plucky/universe s390x libkrb5-26t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [227 kB] 518s Get:33 http://ftpmaster.internal/ubuntu plucky/universe s390x libheimntlm0t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [17.0 kB] 518s Get:34 http://ftpmaster.internal/ubuntu plucky/universe s390x libgssapi3t64-heimdal s390x 7.8.git20221117.28daf24+dfsg-8ubuntu1 [105 kB] 518s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x libc6-dbg s390x 2.40-4ubuntu1 [7794 kB] 518s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x valgrind s390x 1:3.24.0-0ubuntu1 [9636 kB] 518s Get:37 http://ftpmaster.internal/ubuntu plucky/universe s390x valgrind-if-available s390x 3.18.1-1-1ubuntu1 [2150 B] 519s Preconfiguring packages ... 519s Fetched 23.6 MB in 1s (16.0 MB/s) 519s Selecting previously unselected package ssl-cert. 519s (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 ... 55928 files and directories currently installed.) 519s Preparing to unpack .../00-ssl-cert_1.1.3ubuntu1_all.deb ... 519s Unpacking ssl-cert (1.1.3ubuntu1) ... 519s Selecting previously unselected package libexttextcat-data. 519s Preparing to unpack .../01-libexttextcat-data_3.4.7-1build1_all.deb ... 519s Unpacking libexttextcat-data (3.4.7-1build1) ... 519s Selecting previously unselected package libexttextcat-2.0-0:s390x. 519s Preparing to unpack .../02-libexttextcat-2.0-0_3.4.7-1build1_s390x.deb ... 519s Unpacking libexttextcat-2.0-0:s390x (3.4.7-1build1) ... 519s Selecting previously unselected package liblua5.4-0:s390x. 519s Preparing to unpack .../03-liblua5.4-0_5.4.7-1_s390x.deb ... 519s Unpacking liblua5.4-0:s390x (5.4.7-1) ... 519s Selecting previously unselected package libsodium23:s390x. 519s Preparing to unpack .../04-libsodium23_1.0.18-1build3_s390x.deb ... 519s Unpacking libsodium23:s390x (1.0.18-1build3) ... 519s Selecting previously unselected package libstemmer0d:s390x. 519s Preparing to unpack .../05-libstemmer0d_2.2.0-4build1_s390x.deb ... 519s Unpacking libstemmer0d:s390x (2.2.0-4build1) ... 519s Selecting previously unselected package dovecot-core. 519s Preparing to unpack .../06-dovecot-core_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 519s Unpacking dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 519s Selecting previously unselected package krb5-config. 519s Preparing to unpack .../07-krb5-config_2.7_all.deb ... 519s Unpacking krb5-config (2.7) ... 519s Selecting previously unselected package libgssrpc4t64:s390x. 519s Preparing to unpack .../08-libgssrpc4t64_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package libkadm5clnt-mit12:s390x. 519s Preparing to unpack .../09-libkadm5clnt-mit12_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package libkdb5-10t64:s390x. 519s Preparing to unpack .../10-libkdb5-10t64_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package libkadm5srv-mit12:s390x. 519s Preparing to unpack .../11-libkadm5srv-mit12_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package krb5-user. 519s Preparing to unpack .../12-krb5-user_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking krb5-user (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package libevent-2.1-7t64:s390x. 519s Preparing to unpack .../13-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 519s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 519s Selecting previously unselected package libverto1t64:s390x. 519s Preparing to unpack .../14-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 519s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 519s Selecting previously unselected package libverto-libevent1t64:s390x. 519s Preparing to unpack .../15-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 519s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 519s Selecting previously unselected package krb5-kdc. 519s Preparing to unpack .../16-krb5-kdc_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package krb5-admin-server. 519s Preparing to unpack .../17-krb5-admin-server_1.21.3-4ubuntu1_s390x.deb ... 519s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 519s Selecting previously unselected package dovecot-gssapi. 519s Preparing to unpack .../18-dovecot-gssapi_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 519s Unpacking dovecot-gssapi (1:2.3.21.1+dfsg1-1ubuntu2) ... 519s Selecting previously unselected package dovecot-imapd. 519s Preparing to unpack .../19-dovecot-imapd_1%3a2.3.21.1+dfsg1-1ubuntu2_s390x.deb ... 519s Unpacking dovecot-imapd (1:2.3.21.1+dfsg1-1ubuntu2) ... 519s Selecting previously unselected package libgssglue1:s390x. 519s Preparing to unpack .../20-libgssglue1_0.9-1build1_s390x.deb ... 519s Unpacking libgssglue1:s390x (0.9-1build1) ... 519s Selecting previously unselected package libidn12:s390x. 519s Preparing to unpack .../21-libidn12_1.42-3_s390x.deb ... 519s Unpacking libidn12:s390x (1.42-3) ... 519s Selecting previously unselected package libntlm0:s390x. 519s Preparing to unpack .../22-libntlm0_1.8-3_s390x.deb ... 519s Unpacking libntlm0:s390x (1.8-3) ... 519s Selecting previously unselected package libgsasl18:s390x. 519s Preparing to unpack .../23-libgsasl18_2.2.1-1willsync1ubuntu1_s390x.deb ... 519s Unpacking libgsasl18:s390x (2.2.1-1willsync1ubuntu1) ... 519s Selecting previously unselected package gsasl. 519s Preparing to unpack .../24-gsasl_2.2.1-1willsync1ubuntu1_s390x.deb ... 519s Unpacking gsasl (2.2.1-1willsync1ubuntu1) ... 519s Selecting previously unselected package libroken19t64-heimdal:s390x. 519s Preparing to unpack .../25-libroken19t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libroken19t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libasn1-8t64-heimdal:s390x. 519s Preparing to unpack .../26-libasn1-8t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libasn1-8t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libheimbase1t64-heimdal:s390x. 519s Preparing to unpack .../27-libheimbase1t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libheimbase1t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libhcrypto5t64-heimdal:s390x. 519s Preparing to unpack .../28-libhcrypto5t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libhcrypto5t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libwind0t64-heimdal:s390x. 519s Preparing to unpack .../29-libwind0t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libwind0t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libhx509-5t64-heimdal:s390x. 519s Preparing to unpack .../30-libhx509-5t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libhx509-5t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libkrb5-26t64-heimdal:s390x. 519s Preparing to unpack .../31-libkrb5-26t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libkrb5-26t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libheimntlm0t64-heimdal:s390x. 519s Preparing to unpack .../32-libheimntlm0t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libheimntlm0t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libgssapi3t64-heimdal:s390x. 519s Preparing to unpack .../33-libgssapi3t64-heimdal_7.8.git20221117.28daf24+dfsg-8ubuntu1_s390x.deb ... 519s Unpacking libgssapi3t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 519s Selecting previously unselected package libc6-dbg:s390x. 519s Preparing to unpack .../34-libc6-dbg_2.40-4ubuntu1_s390x.deb ... 519s Unpacking libc6-dbg:s390x (2.40-4ubuntu1) ... 519s Selecting previously unselected package valgrind. 519s Preparing to unpack .../35-valgrind_1%3a3.24.0-0ubuntu1_s390x.deb ... 519s Unpacking valgrind (1:3.24.0-0ubuntu1) ... 520s Selecting previously unselected package valgrind-if-available. 520s Preparing to unpack .../36-valgrind-if-available_3.18.1-1-1ubuntu1_s390x.deb ... 520s Unpacking valgrind-if-available (3.18.1-1-1ubuntu1) ... 520s Setting up libsodium23:s390x (1.0.18-1build3) ... 520s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 520s Setting up libroken19t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 520s Setting up libc6-dbg:s390x (2.40-4ubuntu1) ... 520s Setting up libntlm0:s390x (1.8-3) ... 520s Setting up ssl-cert (1.1.3ubuntu1) ... 520s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 520s Setting up libgssrpc4t64:s390x (1.21.3-4ubuntu1) ... 520s Setting up krb5-config (2.7) ... 520s Setting up libidn12:s390x (1.42-3) ... 520s Setting up libgssglue1:s390x (0.9-1build1) ... 520s Setting up libgsasl18:s390x (2.2.1-1willsync1ubuntu1) ... 520s Setting up gsasl (2.2.1-1willsync1ubuntu1) ... 520s Setting up libwind0t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 520s Setting up libexttextcat-data (3.4.7-1build1) ... 520s Setting up liblua5.4-0:s390x (5.4.7-1) ... 520s Setting up libstemmer0d:s390x (2.2.0-4build1) ... 520s Setting up valgrind (1:3.24.0-0ubuntu1) ... 520s Setting up libheimbase1t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 520s Setting up libkadm5clnt-mit12:s390x (1.21.3-4ubuntu1) ... 520s Setting up valgrind-if-available (3.18.1-1-1ubuntu1) ... 520s Setting up libexttextcat-2.0-0:s390x (3.4.7-1build1) ... 520s Setting up libkdb5-10t64:s390x (1.21.3-4ubuntu1) ... 520s Setting up libasn1-8t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 520s Setting up libkadm5srv-mit12:s390x (1.21.3-4ubuntu1) ... 520s Setting up dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 521s Creating config file /etc/dovecot/dovecot.conf with new version 521s Creating config file /etc/dovecot/dovecot-dict-auth.conf.ext with new version 521s Creating config file /etc/dovecot/dovecot-dict-sql.conf.ext with new version 521s Creating config file /etc/dovecot/dovecot-sql.conf.ext with new version 521s Creating config file /etc/dovecot/conf.d/10-auth.conf with new version 521s Creating config file /etc/dovecot/conf.d/10-director.conf with new version 521s Creating config file /etc/dovecot/conf.d/10-logging.conf with new version 521s Creating config file /etc/dovecot/conf.d/10-mail.conf with new version 522s Creating config file /etc/dovecot/conf.d/10-master.conf with new version 522s Creating config file /etc/dovecot/conf.d/10-ssl.conf with new version 522s Creating config file /etc/dovecot/conf.d/10-tcpwrapper.conf with new version 522s Creating config file /etc/dovecot/conf.d/15-lda.conf with new version 522s Creating config file /etc/dovecot/conf.d/15-mailboxes.conf with new version 522s Creating config file /etc/dovecot/conf.d/90-acl.conf with new version 522s Creating config file /etc/dovecot/conf.d/90-plugin.conf with new version 522s Creating config file /etc/dovecot/conf.d/90-quota.conf with new version 522s Creating config file /etc/dovecot/conf.d/auth-checkpassword.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-deny.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-dict.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-master.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-passwdfile.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-sql.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-static.conf.ext with new version 523s Creating config file /etc/dovecot/conf.d/auth-system.conf.ext with new version 524s Created symlink '/etc/systemd/system/multi-user.target.wants/dovecot.service' → '/usr/lib/systemd/system/dovecot.service'. 524s dovecot.socket is a disabled or a static unit, not starting it. 524s Setting up dovecot-imapd (1:2.3.21.1+dfsg1-1ubuntu2) ... 524s Creating config file /etc/dovecot/conf.d/20-imap.conf with new version 524s Setting up libhcrypto5t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 524s Setting up dovecot-gssapi (1:2.3.21.1+dfsg1-1ubuntu2) ... 524s Setting up krb5-user (1.21.3-4ubuntu1) ... 524s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 524s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 524s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 524s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 524s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 524s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 524s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 524s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 524s Setting up libhx509-5t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 524s Setting up libkrb5-26t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 524s Setting up libheimntlm0t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 524s Setting up libgssapi3t64-heimdal:s390x (7.8.git20221117.28daf24+dfsg-8ubuntu1) ... 524s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 524s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 524s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 525s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 525s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 525s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 525s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 526s Processing triggers for libc-bin (2.40-4ubuntu1) ... 526s Processing triggers for ufw (0.36.2-9) ... 526s Processing triggers for man-db (2.13.0-1) ... 527s Processing triggers for dovecot-core (1:2.3.21.1+dfsg1-1ubuntu2) ... 529s autopkgtest [20:42:05]: test gsasl-dovecot-gssapi-heimdal: [----------------------- 530s /usr/bin/valgrind 530s + command -v valgrind 530s + VALGRIND=valgrind --error-exitcode=1 530s + echo libgssapi.so.3 mechglue_internal_krb5_init 530s + GSSAPI_MECH_CONF=my_gssapi_mech.conf GSASL=valgrind --error-exitcode=1 /usr/bin/gsasl debian/tests/gsasl-dovecot-gssapi.sh 530s + : valgrind --error-exitcode=1 /usr/bin/gsasl 530s + valgrind --error-exitcode=1 /usr/bin/gsasl --version 530s + grep ^gsasl (GNU SASL 530s gsasl (GNU SASL) 2.2.1 530s + valgrind --error-exitcode=1 /usr/bin/gsasl --client-mechanisms 530s + grep GSSAPI 531s ANONYMOUS EXTERNAL LOGIN PLAIN SECURID NTLM DIGEST-MD5 CRAM-MD5 SCRAM-SHA-1 SCRAM-SHA-1-PLUS SCRAM-SHA-256 SCRAM-SHA-256-PLUS SAML20 OPENID20 GSSAPI GS2-KRB5 531s /usr/bin/ss 531s /usr/bin/id 531s /usr/bin/hostname 531s /usr/sbin/dovecot 531s /usr/bin/kinit 531s /usr/sbin/kdb5_util 531s + test no = yes 531s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 531s + command -v ss 531s + command -v id 531s + command -v hostname 531s + command -v dovecot 531s + command -v kinit 531s + command -v kdb5_util 531s + command -v/usr/sbin/kadmin.local 531s /usr/sbin/krb5kdc 531s Initializing database '/tmp/tmp.AZ5w2Fy9KS/principal' for realm 'GSASL.EXAMPLE', 531s master key name 'K/M@GSASL.EXAMPLE' 531s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.AZ5w2Fy9KS/d/dovecot.keytab. 531s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.AZ5w2Fy9KS/d/dovecot.keytab. 531s tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 531s kadmin.local 531s + command -v krb5kdc 531s + mktemp -d 531s + WORKDIR=/tmp/tmp.AZ5w2Fy9KS 531s + trap set +e; test -f $WORKDIR/pid && kill `cat $WORKDIR/pid`; dovecot -c $WORKDIR/d/dovecot.conf stop; tail -v -n +0 $WORKDIR/out-* $WORKDIR/dovecot.log $WORKDIR/kdc.log; rm -rfv $WORKDIR 0 INT QUIT ABRT PIPE TERM 531s + : ubuntu 531s + id -gn 531s + : ubuntu 531s + mkdir /tmp/tmp.AZ5w2Fy9KS/k /tmp/tmp.AZ5w2Fy9KS/d 531s + cat 531s + hostname -d 531s + hostname -f 531s + cat 531s + hostname -f 531s + cat 531s + test ubuntu = root 531s + cat 531s + export KRB5CCNAME=/tmp/tmp.AZ5w2Fy9KS/cc 531s + export KRB5_CONFIG=/tmp/tmp.AZ5w2Fy9KS/k/krb5.conf 531s + export KRB5_KDC_PROFILE=/tmp/tmp.AZ5w2Fy9KS/k 531s + kdb5_util -P foo create -s 531s + hostname -f 531s + kadmin.local addprinc -randkey imap/autopkgtest.local 531s + kadmin.local addprinc -pw bar ubuntu 531s + hostname -f 531s + kadmin.local ktadd -k /tmp/tmp.AZ5w2Fy9KS/d/dovecot.keytab imap/autopkgtest.local 531s + i=0 531s + krb5kdc -n -P /tmp/tmp.AZ5w2Fy9KS/pid 531s + ss -na 531s + grep 0.0.0.0:17643 531s + grep LISTEN 531s krb5kdc: starting... 531s + dovecot -c /tmp/tmp.AZ5w2Fy9KS/d/dovecot.conf 531s + hostname -f 531s + valgrind --error-exitcode=1 /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 532s + grep -q gss_init_sec_context /tmp/tmp.AZ5w2Fy9KS/out-err 532s + echo bar 532s + kinit ubuntu 532s Password for ubuntu@GSASL.EXAMPLE: 532s + hostname -f 532s + valgrind --error-exitcode=1 /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 534s PASS: debian/tests/gsasl-dovecot-gssapi.sh 534s + grep -q OK Logged in /tmp/tmp.AZ5w2Fy9KS/out-ok 534s + echo PASS: debian/tests/gsasl-dovecot-gssapi.sh 534s + exit 0 534s + set +e 534s + test -f /tmp/tmp.AZ5w2Fy9KS/pid 534s + cat /tmp/tmp.AZ5w2Fy9KS/pid 534s + kill 4710 534s + dovecot -c /tmp/tmp.AZ5w2Fy9KS/d/dovecot.conf stop 535s + tail -v -n +0 /tmp/tmp.AZ5w2Fy9KS/out-err /tmp/tmp.AZ5w2Fy9KS/out-ok /tmp/tmp.AZ5w2Fy9KS/dovecot.log /tmp/tmp.AZ5w2Fy9KS/kdc.log 535s ==> /tmp/tmp.AZ5w2Fy9KS/out-err <== 535s ==4731== Memcheck, a memory error detector 535s ==4731== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 535s ==4731== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 535s ==4731== Command: /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 535s ==4731== 535s Trying ‘autopkgtest.local’... 535s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 535s . CAPABILITY 535s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 535s . OK Pre-login capabilities listed, post-login capabilities have more. 535s . AUTHENTICATE GSSAPI 535s + 535s /usr/bin/gsasl: mechanism error: GSSAPI error in client while negotiating security context in gss_init_sec_context() in SASL library. This is most likely due insufficient credentials or malicious interactions. 535s ==4731== 535s ==4731== HEAP SUMMARY: 535s ==4731== in use at exit: 29,641 bytes in 135 blocks 535s ==4731== total heap usage: 1,834 allocs, 1,699 frees, 249,201 bytes allocated 535s ==4731== 535s ==4731== LEAK SUMMARY: 535s ==4731== definitely lost: 16 bytes in 1 blocks 535s ==4731== indirectly lost: 0 bytes in 0 blocks 535s ==4731== possibly lost: 437 bytes in 6 blocks 535s ==4731== still reachable: 29,188 bytes in 128 blocks 535s ==4731== suppressed: 0 bytes in 0 blocks 535s ==4731== Rerun with --leak-check=full to see details of leaked memory 535s ==4731== 535s ==4731== For lists of detected and suppressed errors, rerun with: -s 535s ==4731== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 535s 535s ==> /tmp/tmp.AZ5w2Fy9KS/out-ok <== 535s ==4742== Memcheck, a memory error detector 535s ==4742== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 535s ==4742== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 535s ==4742== Command: /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 535s ==4742== 535s Trying ‘autopkgtest.local’... 535s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 535s . CAPABILITY 535s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 535s . OK Pre-login capabilities listed, post-login capabilities have more. 535s . AUTHENTICATE GSSAPI 535s + 535s YIIDLAYJKoZIhvcSAQICAQBuggMbMIIDF6ADAgEFoQMCAQ6iBwMFACAAAACjggH6YYIB9jCCAfKgAwIBBaEPGw1HU0FTTC5FWEFNUExFoiQwIqADAgEBoRswGRsEaW1hcBsRYXV0b3BrZ3Rlc3QubG9jYWyjggGyMIIBrqADAgESoQMCAQKiggGgBIIBnCKgZBkvJjTqqi/54mxihwotLlBB43JKFQ2nY6Lrgu7rBypUKhtdJhTfwnyyVi+iUrMEOJin+7kotQwbtxIfMgDTXS8LkBRbI3GvyfQLRrrcqc2sq8N8r7VafWIX0uOoa7iVlT7bJbLHzCgVKVPCCX3TkJ5GqAZ0lctqb3mJN7CzsCX3hT8Z2MDo5sNLALT4J53D4um7mNgOuNxsvJedc2K8i0LJkP8iNR5/prxW0LGlzjuRDkYtr0PXwtreE3zNwpY9ADivFPxjGHXrO3jUAgIvRD6y17PWAV0hetdapvTfK48NyAW2JjL9Lmy4q9MDDOkKHVRAi33BRlyWaR9QT95kbczWSO0XKEixJZ2D/kaeYLJY6LnOMZLqWEMCFsV2AO80rsRxm3UWdT01bCjz5aa/qUGNMxKs+SSGEUbpNVADDzhPohODOvig4oT3OvnMRTUCNh+GHpkGklBQAYyZJ7B96zlNp/B99Irk+1SYsroKDHVFJprorcCY7cghrYEtnwB/9YESB/vXe/hCyYXMsJHCOtASKAtNvzpOp7CkggECMIH/oAMCARKigfcEgfTnABH7l2SooMRzIMm2ERjM4DKULUyqCYDkQ3Y0bSdmxJ12rvhmnf47ka3oYX/1qVYEujo6iZJbJENYF4M5DPFwMXxsBSUMyzSVvHAtftIgHIIb9qpX6Tj4LeNxO9P1OP+ipb8d5qpDl3XXexISICzUG1F2cXPNvqGLSGUUBTEo3rZIbXtVoHto60EeBaPFFlmi7LDuqSSlNiMjc+mkj/ChGAppavzXzCknF5N1cqaGS1ayCR2nh3Cp2Ww28t6oWHz0lnm82I3negjWGOQI+aE2UZUge4Bk+bSnsrhc08uZgNQR13wQebqJOVRxzSFdrDJ06/aa 535s + YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvgjZhWlmr5rGh9X2wyV8dsH76cxvq8YUxdWzqlo1/XiQp8CyMEXgcCI2foPmY5EIBNH9xhkwvsSJJD7s7J+v8abvtwKcU1wdqraNlUbEz0oFCKOTGSXYOOBTLoV1MnZhRjQi26qdZ9SgiusZeZuYM 535s 535s + BQQF/wAMAAAAAAAAK7jpdAH////FYVEW/DEIZOqyPiM= 535s BQQE/wAMAAwAAAAAB1X2U9y2FdSVxn0t2FvUHAH///8= 535s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY 535s . OK Logged in 535s Client authentication finished (server trusted)... 535s Session finished... 535s . LOGOUT 535s * BYE Logging out 535s . OK Logout completed (0.001 + 0.000 secs). 535s ==4742== 535s ==4742== HEAP SUMMARY: 535s ==4742== in use at exit: 25,177 bytes in 117 blocks 535s ==4742== total heap usage: 2,700 allocs, 2,583 frees, 489,251 bytes allocated 535s ==4742== 535s ==4742== LEAK SUMMARY: 535s ==4742== definitely lost: 16 bytes in 1 blocks 535s ==4742== indirectly lost: 0 bytes in 0 blocks 535s ==4742== possibly lost: 437 bytes in 6 blocks 535s ==4742== still reachable: 24,724 bytes in 110 blocks 535s ==4742== suppressed: 0 bytes in 0 blocks 535s ==4742== Rerun with --leak-check=full to see details of leaked memory 535s ==4742== 535s ==4742== For lists of detected and suppressed errors, rerun with: -s 535s ==4742== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 535s 535s ==> /tmp/tmp.AZ5w2Fy9KS/dovecot.log <== 535s Feb 21 20:43:33 master: Info: Dovecot v2.3.21.1 (d492236fa0) starting up for imap (core dumps disabled) 535s Feb 21 20:43:33 master: Error: file_dotlock_open(/var/lib/dovecot/instances) failed: Permission denied 535s Feb 21 20:43:33 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 535s Feb 21 20:43:33 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so 535s Feb 21 20:43:33 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 535s Feb 21 20:43:33 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libmech_gssapi.so 535s Feb 21 20:43:33 auth: Debug: Wrote new auth token secret to /tmp/tmp.AZ5w2Fy9KS/b/auth-token-secret.dat 535s Feb 21 20:43:33 auth: Debug: auth client connected (pid=4735) 535s Feb 21 20:43:33 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=76xLC60ujsx/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=52366 535s Feb 21 20:43:33 auth: Debug: gssapi(?,127.0.0.1,<76xLC60ujsx/AAAB>): Obtaining credentials for imap@autopkgtest.local 535s Feb 21 20:43:33 auth: Debug: client passdb out: CONT 1 535s Feb 21 20:43:34 imap-login: Info: Disconnected: Connection closed (client didn't finish SASL auth, waited 1 secs): user=<>, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, session=<76xLC60ujsx/AAAB> 535s Feb 21 20:43:34 auth: Debug: client in: CANCEL 1 535s Feb 21 20:43:35 auth: Debug: auth client connected (pid=4743) 535s Feb 21 20:43:35 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=GAVcC60ulMx/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=52372 535s Feb 21 20:43:35 auth: Debug: gssapi(?,127.0.0.1,): Obtaining credentials for imap@autopkgtest.local 535s Feb 21 20:43:35 auth: Debug: client passdb out: CONT 1 535s Feb 21 20:43:35 auth: Debug: client in: CONT 535s Feb 21 20:43:35 auth: Debug: gssapi(ubuntu@gsasl.example,127.0.0.1,): security context state completed. 535s Feb 21 20:43:35 auth: Debug: client passdb out: CONT 1 YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvgjZhWlmr5rGh9X2wyV8dsH76cxvq8YUxdWzqlo1/XiQp8CyMEXgcCI2foPmY5EIBNH9xhkwvsSJJD7s7J+v8abvtwKcU1wdqraNlUbEz0oFCKOTGSXYOOBTLoV1MnZhRjQi26qdZ9SgiusZeZuYM 535s Feb 21 20:43:35 auth: Debug: client in: CONT 535s Feb 21 20:43:35 auth: Debug: gssapi(ubuntu@gsasl.example,127.0.0.1,): Negotiated security layer 535s Feb 21 20:43:35 auth: Debug: client passdb out: CONT 1 BQQF/wAMAAAAAAAAK7jpdAH////FYVEW/DEIZOqyPiM= 535s Feb 21 20:43:35 auth: Debug: client in: CONT 535s Feb 21 20:43:35 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Performing passdb lookup 535s Feb 21 20:43:35 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): lookup 535s Feb 21 20:43:35 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Finished passdb lookup 535s Feb 21 20:43:35 auth: Debug: auth(ubuntu@gsasl.example,127.0.0.1,): Auth request finished 535s Feb 21 20:43:35 auth: Debug: client passdb out: OK 1 user=ubuntu@gsasl.example original_user=ubuntu@GSASL.EXAMPLE 535s Feb 21 20:43:35 auth: Debug: master in: REQUEST 366870529 4743 1 c6d523e990c2a8c00e1e20e80b323d4c session_pid=4744 request_auth_token 535s Feb 21 20:43:35 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Performing userdb lookup 535s Feb 21 20:43:35 auth: Debug: static(ubuntu@gsasl.example,127.0.0.1,): Finished userdb lookup 535s Feb 21 20:43:35 auth: Debug: master userdb out: USER 366870529 ubuntu@gsasl.example uid=1000 gid=1000 home=/tmp/tmp.AZ5w2Fy9KS mail=mbox:foo auth_mech=GSSAPI auth_token=59e2f6ca33c5ec98c86652c5a9ea2fafd7501bc8 auth_user=ubuntu@GSASL.EXAMPLE 535s Feb 21 20:43:35 imap-login: Info: Login: user=, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, mpid=4744, session= 535s Feb 21 20:43:35 imap(ubuntu@gsasl.example)<4744>: Info: Disconnected: Logged out in=8 out=469 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0 535s Feb 21 20:43:35 master: Warning: Killed with signal 15 (by pid=4747 uid=1000 code=kill) 535s Feb 21 20:43:35 master: Error: file_dotlock_open(/var/lib/dovecot/instances) failed: Permission denied 535s 535s ==> /tmp/tmp.AZ5w2Fy9KS/kdc.log <== 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](Error): preauth spake failed to initialize: No SPAKE preauth groups configured 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](info): setting up network... 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](info): setsockopt(10,IPV6_V6ONLY,1) worked 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](info): setsockopt(12,IPV6_V6ONLY,1) worked 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](info): set up 4 sockets 535s Feb 21 20:43:33 autopkgtest krb5kdc[4710](info): commencing operation 535s Feb 21 20:43:34 autopkgtest krb5kdc[4710](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: NEEDED_PREAUTH: ubuntu@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE, Additional pre-authentication required 535s Feb 21 20:43:34 autopkgtest krb5kdc[4710](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: ISSUE: authtime 1740170614, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, ubuntu@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE 535s Feb 21 20:43:35 autopkgtest krb5kdc[4710](info): TGS_REQ (6 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23)}) 127.0.0.1: ISSUE: authtime 1740170614, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, ubuntu@GSASL.EXAMPLE for imap/autopkgtest.local@GSASL.EXAMPLE 535s Feb 21 20:43:35 autopkgtest krb5kdc[4710](info): shutting down 535s + rm -rfv /tmp/tmp.AZ5w2Fy9KS 535s + exit 0 535s removed '/tmp/tmp.AZ5w2Fy9KS/pid' 535s removed '/tmp/tmp.AZ5w2Fy9KS/d/dovecot.keytab' 535s removed '/tmp/tmp.AZ5w2Fy9KS/d/dovecot.conf' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/d' 535s removed '/tmp/tmp.AZ5w2Fy9KS/stash' 535s removed '/tmp/tmp.AZ5w2Fy9KS/kdc.log' 535s removed '/tmp/tmp.AZ5w2Fy9KS/dovecot.log' 535s removed '/tmp/tmp.AZ5w2Fy9KS/principal.ok' 535s removed '/tmp/tmp.AZ5w2Fy9KS/k/kdc.conf' 535s removed '/tmp/tmp.AZ5w2Fy9KS/k/krb5.conf' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/k' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/imap-urlauth-worker' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/old-stats-user' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/anvil-auth-penalty' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/b/empty' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/indexer-worker' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/master' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/dict-async' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/log-errors' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-userdb' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/indexer' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-login' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/config' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/login/ipc-proxy' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/login/imap' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/login/login' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/login/dns-client' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/login/stats-writer' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/b/login' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-client' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/dns-client' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/old-stats-mail' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/old-stats' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/stats-reader' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/dict' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/doveadm-server' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/anvil' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/director-admin' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/stats-writer' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/imap-urlauth' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/imap-master' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/replication-notify-fifo' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/ipc' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/token-login/imap-urlauth' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/token-login/tokenlogin' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/b/token-login' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/dovecot.conf' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-worker' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-token-secret.dat' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/replicator' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/imap-hibernate' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/auth-master' 535s removed '/tmp/tmp.AZ5w2Fy9KS/b/replication-notify' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS/b' 535s removed '/tmp/tmp.AZ5w2Fy9KS/principal.kadm5' 535s removed '/tmp/tmp.AZ5w2Fy9KS/out-ok' 535s removed '/tmp/tmp.AZ5w2Fy9KS/out-err' 535s removed '/tmp/tmp.AZ5w2Fy9KS/principal.kadm5.lock' 535s removed '/tmp/tmp.AZ5w2Fy9KS/principal' 535s removed '/tmp/tmp.AZ5w2Fy9KS/cc' 535s removed directory '/tmp/tmp.AZ5w2Fy9KS' 535s autopkgtest [20:42:11]: test gsasl-dovecot-gssapi-heimdal: -----------------------] 536s gsasl-dovecot-gssapi-heimdal PASS 536s autopkgtest [20:42:12]: test gsasl-dovecot-gssapi-heimdal: - - - - - - - - - - results - - - - - - - - - - 536s autopkgtest [20:42:12]: test gsasl-dovecot-gssapi-heimdal-root: preparing testbed 536s Reading package lists... 536s Building dependency tree... 536s Reading state information... 537s Starting pkgProblemResolver with broken count: 0 537s Starting 2 pkgProblemResolver with broken count: 0 537s Done 537s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 537s autopkgtest [20:42:13]: test gsasl-dovecot-gssapi-heimdal-root: [----------------------- 537s /usr/bin/valgrind 537s + command -v valgrind 537s + VALGRIND=valgrind --error-exitcode=1 537s + echo libgssapi.so.3 mechglue_internal_krb5_init 537s + GSASL=valgrind --error-exitcode=1 /usr/bin/gsasl debian/tests/gsasl-dovecot-gssapi.sh 537s + : valgrind --error-exitcode=1 /usr/bin/gsasl 537s + valgrind --error-exitcode=1 /usr/bin/gsasl --version 537s + grep ^gsasl (GNU SASL 538s gsasl (GNU SASL) 2.2.1 538s + valgrind --error-exitcode=1 /usr/bin/gsasl --client-mechanisms 538s + grep GSSAPI 539s ANONYMOUS EXTERNAL LOGIN PLAIN SECURID NTLM DIGEST-MD5 CRAM-MD5 SCRAM-SHA-1 SCRAM-SHA-1-PLUS SCRAM-SHA-256 SCRAM-SHA-256-PLUS SAML20 OPENID20 GSSAPI GS2-KRB5 539s + test no = yes 539s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 539s + command -v ss 539s + command -v id 539s + command -v hostname 539s + command -v dovecot 539s + command -v kinit 539s + command -v kdb5_util 539s + command -v kadmin.local 539s + command -v krb5kdc 539s + mktemp -d 539s /usr/bin/ss 539s /usr/bin/id 539s /usr/bin/hostname 539s /usr/sbin/dovecot 539s /usr/bin/kinit 539s /usr/sbin/kdb5_util 539s /usr/sbin/kadmin.local 539s /usr/sbin/krb5kdc 539s + WORKDIR=/tmp/tmp.K0CCWdmoh2 539s + trap set +e; test -f $WORKDIR/pid && kill `cat $WORKDIR/pid`; dovecot -c $WORKDIR/d/dovecot.conf stop; tail -v -n +0 $WORKDIR/out-* $WORKDIR/dovecot.log $WORKDIR/kdc.log; rm -rfv $WORKDIR 0 INT QUIT ABRT PIPE TERM 539s + : root 539s + id -gn 539s + : root 539s + mkdir /tmp/tmp.K0CCWdmoh2/k /tmp/tmp.K0CCWdmoh2/d 539s + cat 539s + hostname -d 539s + hostname -f 539s + cat 539s + hostname -f 539s + cat 539s + test root = root 539s + cat 539s + export KRB5CCNAME=/tmp/tmp.K0CCWdmoh2/cc 539s + export KRB5_CONFIG=/tmp/tmp.K0CCWdmoh2/k/krb5.conf 539s + export KRB5_KDC_PROFILE=/tmp/tmp.K0CCWdmoh2/k 539s + kdb5_util -P foo create -s 539s + hostname -f 539s + kadmin.local addprinc -randkey imap/autopkgtest.local 539s + kadmin.local addprinc -pw bar root 539s + hostname -f 539s + kadmin.local ktadd -k /tmp/tmp.K0CCWdmoh2/d/dovecot.keytab imap/autopkgtest.local 539s + i=0 539s + krb5kdc -n -P /tmp/tmp.K0CCWdmoh2/pid 539s + ss -na 539s + grep 0.0.0.0:17643 539s + grep LISTEN 539s krb5kdc: starting... 539s + expr 0 + 1 539s + i=1 539s + test 1 = 10 539s + sleep 1 539s Initializing database '/tmp/tmp.K0CCWdmoh2/principal' for realm 'GSASL.EXAMPLE', 539s master key name 'K/M@GSASL.EXAMPLE' 539s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.K0CCWdmoh2/d/dovecot.keytab. 539s Entry for principal imap/autopkgtest.local with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/tmp.K0CCWdmoh2/d/dovecot.keytab. 540s + tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 540s + ss -na 540s grep 0.0.0.0:17643 540s + grep LISTEN 540s + dovecot -c /tmp/tmp.K0CCWdmoh2/d/dovecot.conf 540s + hostname -f 540s + valgrind --error-exitcode=1 /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 541s + grep -q gss_init_sec_context /tmp/tmp.K0CCWdmoh2/out-err 541s + echo bar 541s + kinit root 541s Password for root@GSASL.EXAMPLE: 541s + hostname -f 541s + valgrind --error-exitcode=1 /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 543s + grep -q OK Logged in /tmp/tmp.K0CCWdmoh2/out-ok 543s + echo PASS: debian/tests/gsasl-dovecot-gssapi.sh 543s + exit 0 543s + set +e 543s + test -f /tmp/tmp.K0CCWdmoh2/pid 543s PASS: debian/tests/gsasl-dovecot-gssapi.sh 543s + cat /tmp/tmp.K0CCWdmoh2/pid 543s + kill 4905 543s + dovecot -c /tmp/tmp.K0CCWdmoh2/d/dovecot.conf stop 544s + tail -v -n +0 /tmp/tmp.K0CCWdmoh2/out-err /tmp/tmp.K0CCWdmoh2/out-ok /tmp/tmp.K0CCWdmoh2/dovecot.log /tmp/tmp.K0CCWdmoh2/kdc.log 544s ==> /tmp/tmp.K0CCWdmoh2/out-err <== 544s ==4919== Memcheck, a memory error detector 544s ==4919== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 544s ==4919== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 544s ==4919== Command: /usr/bin/gsasl -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 544s ==4919== 544s Trying ‘autopkgtest.local’... 544s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 544s . CAPABILITY 544s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 544s . OK Pre-login capabilities listed, post-login capabilities have more. 544s . AUTHENTICATE GSSAPI 544s + 544s /usr/bin/gsasl: mechanism error: GSSAPI error in client while negotiating security context in gss_init_sec_context() in SASL library. This is most likely due insufficient credentials or malicious interactions. 544s ==4919== 544s ==4919== HEAP SUMMARY: 544s ==4919== in use at exit: 29,641 bytes in 135 blocks 544s ==4919== total heap usage: 1,834 allocs, 1,699 frees, 249,201 bytes allocated 544s ==4919== 544s ==4919== LEAK SUMMARY: 544s ==4919== definitely lost: 16 bytes in 1 blocks 544s ==4919== indirectly lost: 0 bytes in 0 blocks 544s ==4919== possibly lost: 437 bytes in 6 blocks 544s ==4919== still reachable: 29,188 bytes in 128 blocks 544s ==4919== suppressed: 0 bytes in 0 blocks 544s ==4919== Rerun with --leak-check=full to see details of leaked memory 544s ==4919== 544s ==4919== For lists of detected and suppressed errors, rerun with: -s 544s ==4919== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 544s 544s ==> /tmp/tmp.K0CCWdmoh2/out-ok <== 544s ==4930== Memcheck, a memory error detector 544s ==4930== Copyright (C) 2002-2024, and GNU GPL'd, by Julian Seward et al. 544s ==4930== Using Valgrind-3.24.0 and LibVEX; rerun with -h for copyright info 544s ==4930== Command: /usr/bin/gsasl -z -m GSSAPI -d --no-starttls --imap autopkgtest.local 17436 544s ==4930== 544s Trying ‘autopkgtest.local’... 544s * OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI] Dovecot (Ubuntu) ready. 544s . CAPABILITY 544s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=GSSAPI 544s . OK Pre-login capabilities listed, post-login capabilities have more. 544s . AUTHENTICATE GSSAPI 544s + 544s YIIDKAYJKoZIhvcSAQICAQBuggMXMIIDE6ADAgEFoQMCAQ6iBwMFACAAAACjggH4YYIB9DCCAfCgAwIBBaEPGw1HU0FTTC5FWEFNUExFoiQwIqADAgEBoRswGRsEaW1hcBsRYXV0b3BrZ3Rlc3QubG9jYWyjggGwMIIBrKADAgESoQMCAQKiggGeBIIBmrCMRX2B0ZnMFLnvIuGQjUM310T4yKsaluUD92hTBW9rWO1na6hYImxZtMdXAOQm5M5N/1KiRV8jUDo2++R/umP7HitwwihuwiPh1XVdQlv6z3bTnmQXCsxGzQxhgXIRYzHIwzbqE9YhkeKAY654rBkwwHYUOYpDuCCxNvBTta5Z4BKf+Xty+6rhXETNku8xjfY9Whgiso7iKrLe/4yslFThpW7V/ibyY8xoCUDIApltQLiHENEw79Njdt0XcL1D1RwLYqtmNCbBofGpQhuNHV4ilCSOJ8HJ7b1nbkoBzAekZkgRGayGS8YXSwS/cxTpC9VYaqiF8/qRhlRbjyTVu+6nSD1GQfIsudcnyp5EZrkdOMoWt248NUV/Qgqco8JqQT00zKqc9EOVFRLwWdab7vV9NWLzvJsVtXQVpfS3AH1R6+tjmgs3iJ2Jg3ufM9g0KMTH6BudiBk4gJ8rEMEYQNxHtKcWh02CZyUpCBEPoc+0qsMsJS9RwNCX/2w7ZBEtw1Nv8gSv5GNJcG45x35TcXFxMW9ryCWTcWVspIIBADCB/aADAgESooH1BIHyFkO+efYtqnZtwW0Fg/aFscch0ugmuHnqKBdzLu9xBw9oLpWnD9ziA64TjYzO0PXJBBHF7+X9goDjipnTZQkB+t37fsX/rvVzaBdCXCB3n7YLUfnUwKLXHqVRpD1BqF03K+NtXAKE+kJH0/xsGhiWo9rMujMvX3V29NAv4dZuGB68olx9QLHvryhNbKCG6ipgou6ewhtRZAwx++OOXm4ljhHjD2XesEYX6yKgVWpF+E2t//2+tWfcU/x5QapQK9ulxS285SjZLAzk1avyN0Ma19GDw+hME8Iicxuu78E28Zff9Dy9ktrIsm7XEFwGGp4Rgwo= 544s + YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRv+pd8kdHkb6E3TtfGsdXc6g5YJm4r3zj/0o3q+qFVeap7BOX2xU+oU0a//5gCDBieZLFZUC6aE1tWg5/DD/ssvJIXXFujmHOFaWhVMx+pWUvLtxfZs0Hssyf/WlQ88JxVUtHj8JFQpGvl1E9hdzOb 544s 544s + BQQF/wAMAAAAAAAAO+IuhgH///+MsiIWERq5bCv6fro= 544s BQQE/wAMAAwAAAAAJGhDFjlwm5rDB6IVFffNVwH///8= 544s * CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY PREVIEW=FUZZY PREVIEW STATUS=SIZE SAVEDATE LITERAL+ NOTIFY 544s . OK Logged in 544s Client authentication finished (server trusted)... 544s Session finished... 544s . LOGOUT 544s * BYE Logging out 544s . OK Logout completed (0.001 + 0.000 secs). 544s ==4930== 544s ==4930== HEAP SUMMARY: 544s ==4930== in use at exit: 25,177 bytes in 117 blocks 544s ==4930== total heap usage: 2,700 allocs, 2,583 frees, 489,075 bytes allocated 544s ==4930== 544s ==4930== LEAK SUMMARY: 544s ==4930== definitely lost: 16 bytes in 1 blocks 544s ==4930== indirectly lost: 0 bytes in 0 blocks 544s ==4930== possibly lost: 437 bytes in 6 blocks 544s ==4930== still reachable: 24,724 bytes in 110 blocks 544s ==4930== suppressed: 0 bytes in 0 blocks 544s ==4930== Rerun with --leak-check=full to see details of leaked memory 544s ==4930== 544s ==4930== For lists of detected and suppressed errors, rerun with: -s 544s ==4930== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) 544s 544s ==> /tmp/tmp.K0CCWdmoh2/dovecot.log <== 544s Feb 21 20:43:42 master: Info: Dovecot v2.3.21.1 (d492236fa0) starting up for imap (core dumps disabled) 544s Feb 21 20:43:42 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 544s Feb 21 20:43:42 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so 544s Feb 21 20:43:42 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth 544s Feb 21 20:43:42 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libmech_gssapi.so 544s Feb 21 20:43:42 auth: Debug: Wrote new auth token secret to /tmp/tmp.K0CCWdmoh2/b/auth-token-secret.dat 544s Feb 21 20:43:42 auth: Debug: auth client connected (pid=4923) 544s Feb 21 20:43:42 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=nxnSC60u8pd/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=38898 544s Feb 21 20:43:42 auth: Debug: gssapi(?,127.0.0.1,): Obtaining credentials for imap@autopkgtest.local 544s Feb 21 20:43:42 auth: Debug: client passdb out: CONT 1 544s Feb 21 20:43:43 imap-login: Info: Disconnected: Connection closed (client didn't finish SASL auth, waited 1 secs): user=<>, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, session= 544s Feb 21 20:43:43 auth: Debug: client in: CANCEL 1 544s Feb 21 20:43:43 auth: Debug: auth client connected (pid=4931) 544s Feb 21 20:43:43 auth: Debug: client in: AUTH 1 GSSAPI service=imap session=fgHiC60uAJh/AAAB lip=127.0.1.1 rip=127.0.0.1 lport=17436 rport=38912 544s Feb 21 20:43:43 auth: Debug: gssapi(?,127.0.0.1,): Obtaining credentials for imap@autopkgtest.local 544s Feb 21 20:43:43 auth: Debug: client passdb out: CONT 1 544s Feb 21 20:43:44 auth: Debug: client in: CONT 544s Feb 21 20:43:44 auth: Debug: gssapi(root@gsasl.example,127.0.0.1,): security context state completed. 544s Feb 21 20:43:44 auth: Debug: client passdb out: CONT 1 YIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRv+pd8kdHkb6E3TtfGsdXc6g5YJm4r3zj/0o3q+qFVeap7BOX2xU+oU0a//5gCDBieZLFZUC6aE1tWg5/DD/ssvJIXXFujmHOFaWhVMx+pWUvLtxfZs0Hssyf/WlQ88JxVUtHj8JFQpGvl1E9hdzOb 544s Feb 21 20:43:44 auth: Debug: client in: CONT 544s Feb 21 20:43:44 auth: Debug: gssapi(root@gsasl.example,127.0.0.1,): Negotiated security layer 544s Feb 21 20:43:44 auth: Debug: client passdb out: CONT 1 BQQF/wAMAAAAAAAAO+IuhgH///+MsiIWERq5bCv6fro= 544s Feb 21 20:43:44 auth: Debug: client in: CONT 544s Feb 21 20:43:44 auth: Debug: static(root@gsasl.example,127.0.0.1,): Performing passdb lookup 544s Feb 21 20:43:44 auth: Debug: static(root@gsasl.example,127.0.0.1,): lookup 544s Feb 21 20:43:44 auth: Debug: static(root@gsasl.example,127.0.0.1,): Finished passdb lookup 544s Feb 21 20:43:44 auth: Debug: auth(root@gsasl.example,127.0.0.1,): Auth request finished 544s Feb 21 20:43:44 auth: Debug: client passdb out: OK 1 user=root@gsasl.example original_user=root@GSASL.EXAMPLE 544s Feb 21 20:43:44 auth: Debug: master in: REQUEST 2536374273 4931 1 04bba0ccc102f3e233d81a0948188bfc session_pid=4932 request_auth_token 544s Feb 21 20:43:44 auth: Debug: static(root@gsasl.example,127.0.0.1,): Performing userdb lookup 544s Feb 21 20:43:44 auth: Debug: static(root@gsasl.example,127.0.0.1,): Finished userdb lookup 544s Feb 21 20:43:44 auth: Debug: master userdb out: USER 2536374273 root@gsasl.example uid=4711 gid=4711 home=/tmp/tmp.K0CCWdmoh2 mail=mbox:foo auth_mech=GSSAPI auth_token=a671a72c64c50bb113312409758f0067ae93e900 auth_user=root@GSASL.EXAMPLE 544s Feb 21 20:43:44 imap-login: Info: Login: user=, method=GSSAPI, rip=127.0.0.1, lip=127.0.1.1, mpid=4932, session= 544s Feb 21 20:43:44 imap(root@gsasl.example)<4932>: Error: chdir(/tmp/tmp.K0CCWdmoh2/) failed: Permission denied (euid=4711() egid=4711() missing +x perm: /tmp/tmp.K0CCWdmoh2, dir owned by 0:0 mode=0700) 544s Feb 21 20:43:44 imap(root@gsasl.example)<4932>: Info: Disconnected: Logged out in=8 out=469 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0 544s Feb 21 20:43:44 master: Warning: Killed with signal 15 (by pid=4935 uid=0 code=kill) 544s 544s ==> /tmp/tmp.K0CCWdmoh2/kdc.log <== 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](Error): preauth spake failed to initialize: No SPAKE preauth groups configured 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](info): setting up network... 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](info): setsockopt(10,IPV6_V6ONLY,1) worked 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](info): setsockopt(12,IPV6_V6ONLY,1) worked 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](info): set up 4 sockets 544s Feb 21 20:43:41 autopkgtest krb5kdc[4905](info): commencing operation 544s Feb 21 20:43:43 autopkgtest krb5kdc[4905](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: NEEDED_PREAUTH: root@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE, Additional pre-authentication required 544s Feb 21 20:43:43 autopkgtest krb5kdc[4905](info): AS_REQ (8 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23), camellia128-cts-cmac(25), camellia256-cts-cmac(26)}) 127.0.0.1: ISSUE: authtime 1740170623, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, root@GSASL.EXAMPLE for krbtgt/GSASL.EXAMPLE@GSASL.EXAMPLE 544s Feb 21 20:43:44 autopkgtest krb5kdc[4905](info): TGS_REQ (6 etypes {aes256-cts-hmac-sha1-96(18), aes128-cts-hmac-sha1-96(17), aes256-cts-hmac-sha384-192(20), aes128-cts-hmac-sha256-128(19), DEPRECATED:des3-cbc-sha1(16), DEPRECATED:arcfour-hmac(23)}) 127.0.0.1: ISSUE: authtime 1740170623, etypes {rep=aes256-cts-hmac-sha1-96(18), tkt=aes256-cts-hmac-sha1-96(18), ses=aes256-cts-hmac-sha1-96(18)}, root@GSASL.EXAMPLE for imap/autopkgtest.local@GSASL.EXAMPLE 544s Feb 21 20:43:44 autopkgtest krb5kdc[4905](info): shutting down 544s removed '/tmp/tmp.K0CCWdmoh2/pid' 544s removed '/tmp/tmp.K0CCWdmoh2/d/dovecot.keytab' 544s removed '/tmp/tmp.K0CCWdmoh2/d/dovecot.conf' 544s removed directory '/tmp/tmp.K0CCWdmoh2/d' 544s removed '/tmp/tmp.K0CCWdmoh2/stash' 544s removed '/tmp/tmp.K0CCWdmoh2/kdc.log' 544s removed '/tmp/tmp.K0CCWdmoh2/dovecot.log' 544s removed '/tmp/tmp.K0CCWdmoh2/principal.ok' 544s removed '/tmp/tmp.K0CCWdmoh2/k/kdc.conf' 544s removed '/tmp/tmp.K0CCWdmoh2/k/krb5.conf' 544s removed directory '/tmp/tmp.K0CCWdmoh2/k' 544s removed '/tmp/tmp.K0CCWdmoh2/b/imap-urlauth-worker' 544s removed '/tmp/tmp.K0CCWdmoh2/b/old-stats-user' 544s removed '/tmp/tmp.K0CCWdmoh2/b/anvil-auth-penalty' 544s removed directory '/tmp/tmp.K0CCWdmoh2/b/empty' 544s removed '/tmp/tmp.K0CCWdmoh2/b/indexer-worker' 544s removed '/tmp/tmp.K0CCWdmoh2/b/master' 544s removed '/tmp/tmp.K0CCWdmoh2/b/dict-async' 544s removed '/tmp/tmp.K0CCWdmoh2/b/log-errors' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-userdb' 544s removed '/tmp/tmp.K0CCWdmoh2/b/indexer' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-login' 544s removed '/tmp/tmp.K0CCWdmoh2/b/config' 544s removed '/tmp/tmp.K0CCWdmoh2/b/login/ipc-proxy' 544s removed '/tmp/tmp.K0CCWdmoh2/b/login/imap' 544s removed '/tmp/tmp.K0CCWdmoh2/b/login/login' 544s removed '/tmp/tmp.K0CCWdmoh2/b/login/dns-client' 544s removed '/tmp/tmp.K0CCWdmoh2/b/login/stats-writer' 544s removed directory '/tmp/tmp.K0CCWdmoh2/b/login' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-client' 544s removed '/tmp/tmp.K0CCWdmoh2/b/dns-client' 544s removed '/tmp/tmp.K0CCWdmoh2/b/old-stats-mail' 544s removed '/tmp/tmp.K0CCWdmoh2/b/old-stats' 544s removed '/tmp/tmp.K0CCWdmoh2/b/stats-reader' 544s removed '/tmp/tmp.K0CCWdmoh2/b/dict' 544s removed '/tmp/tmp.K0CCWdmoh2/b/doveadm-server' 544s removed '/tmp/tmp.K0CCWdmoh2/b/anvil' 544s removed '/tmp/tmp.K0CCWdmoh2/b/director-admin' 544s removed '/tmp/tmp.K0CCWdmoh2/b/stats-writer' 544s removed '/tmp/tmp.K0CCWdmoh2/b/imap-urlauth' 544s removed '/tmp/tmp.K0CCWdmoh2/b/imap-master' 544s removed '/tmp/tmp.K0CCWdmoh2/b/replication-notify-fifo' 544s removed '/tmp/tmp.K0CCWdmoh2/b/ipc' 544s removed '/tmp/tmp.K0CCWdmoh2/b/token-login/imap-urlauth' 544s removed '/tmp/tmp.K0CCWdmoh2/b/token-login/tokenlogin' 544s removed directory '/tmp/tmp.K0CCWdmoh2/b/token-login' 544s removed '/tmp/tmp.K0CCWdmoh2/b/dovecot.conf' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-worker' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-token-secret.dat' 544s removed '/tmp/tmp.K0CCWdmoh2/b/replicator' 544s removed '/tmp/tmp.K0CCWdmoh2/b/imap-hibernate' 544s removed '/tmp/tmp.K0CCWdmoh2/b/auth-master' 544s removed '/tmp/tmp.K0CCWdmoh2/b/replication-notify' 544s removed directory '/tmp/tmp.K0CCWdmoh2/b' 544s removed '/tmp/tmp.K0CCWdmoh2/principal.kadm5' 544s removed '/tmp/tmp.K0CCWdmoh2/out-ok' 544s removed '/tmp/tmp.K0CCWdmoh2/out-err' 544s removed '/tmp/tmp.K0CCWdmoh2/principal.kadm5.lock' 544s removed '/tmp/tmp.K0CCWdmoh2/principal' 544s removed '/tmp/tmp.K0CCWdmoh2/cc' 544s removed directory '/tmp/tmp.K0CCWdmoh2' 544s + rm -rfv /tmp/tmp.K0CCWdmoh2 544s + exit 0 544s autopkgtest [20:42:20]: test gsasl-dovecot-gssapi-heimdal-root: -----------------------] 545s autopkgtest [20:42:21]: test gsasl-dovecot-gssapi-heimdal-root: - - - - - - - - - - results - - - - - - - - - - 545s gsasl-dovecot-gssapi-heimdal-root PASS 545s autopkgtest [20:42:21]: @@@@@@@@@@@@@@@@@@@@ summary 545s libgssglue PASS 545s bad-configs PASS 545s bad-configs-root PASS 545s gsasl-dovecot-gssapi FAIL non-zero exit status 1 545s gsasl-dovecot-gssapi-heimdal PASS 545s gsasl-dovecot-gssapi-heimdal-root PASS 550s nova [W] Using flock in prodstack6-s390x 550s Creating nova instance adt-plucky-s390x-libgssglue-20250221-203316-juju-7f2275-prod-proposed-migration-environment-15-dbdeb46c-9ab9-4831-b3e2-86732dda50ca from image adt/ubuntu-plucky-s390x-server-20250221.img (UUID 911778ad-2250-45eb-bdc8-6f3429c92c46)... 550s nova [W] Timed out waiting for fa7ca232-7c8d-4478-9135-c0d932dcb775 to get deleted. 550s nova [W] Using flock in prodstack6-s390x 550s Creating nova instance adt-plucky-s390x-libgssglue-20250221-203316-juju-7f2275-prod-proposed-migration-environment-15-dbdeb46c-9ab9-4831-b3e2-86732dda50ca from image adt/ubuntu-plucky-s390x-server-20250221.img (UUID 911778ad-2250-45eb-bdc8-6f3429c92c46)... 550s nova [W] Timed out waiting for 1d40ab88-d7d7-454f-b84f-f2d715f986a3 to get deleted. 550s nova [W] Using flock in prodstack6-s390x 550s Creating nova instance adt-plucky-s390x-libgssglue-20250221-203316-juju-7f2275-prod-proposed-migration-environment-15-dbdeb46c-9ab9-4831-b3e2-86732dda50ca from image adt/ubuntu-plucky-s390x-server-20250221.img (UUID 911778ad-2250-45eb-bdc8-6f3429c92c46)... 550s nova [W] Timed out waiting for 4597a0f7-3a90-4eaa-8d3d-e59b3030d7d1 to get deleted.