0s autopkgtest [06:45:38]: starting date and time: 2025-02-22 06:45:38+0000 0s autopkgtest [06:45:38]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [06:45:38]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4ey6qxqg/out --timeout-copy=6000 -a i386 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-17 --apt-upgrade cyrus-sasl2 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=postgresql-17/17.4-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@lcy02-6.secgroup --name adt-plucky-i386-cyrus-sasl2-20250222-064538-juju-7f2275-prod-proposed-migration-environment-15-db946b34-7819-4f6e-a9af-65ce218466e2 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 51s autopkgtest [06:46:29]: testbed dpkg architecture: amd64 51s autopkgtest [06:46:29]: testbed apt version: 2.9.30ubuntu1 51s autopkgtest [06:46:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 51s autopkgtest [06:46:29]: testbed release detected to be: None 51s autopkgtest [06:46:29]: updating testbed package index (apt update) 52s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 52s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 52s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 52s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 52s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 52s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 52s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 52s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 52s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [129 kB] 52s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [98.8 kB] 52s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2412 B] 52s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [7984 B] 52s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [453 kB] 52s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [223 kB] 52s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [8844 B] 52s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [20.9 kB] 52s Fetched 1656 kB in 0s (5730 kB/s) 53s Reading package lists... 53s + lsb_release --codename --short 53s + RELEASE=plucky 53s + cat 53s + [ plucky != trusty ] 53s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 54s Calculating upgrade... 54s Calculating upgrade... 54s The following packages will be upgraded: 54s curl libcurl3t64-gnutls libcurl4t64 54s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 54s Need to get 1121 kB of archives. 54s After this operation, 7168 B disk space will be freed. 54s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 curl amd64 8.12.1-2ubuntu1 [251 kB] 54s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libcurl4t64 amd64 8.12.1-2ubuntu1 [437 kB] 54s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libcurl3t64-gnutls amd64 8.12.1-2ubuntu1 [432 kB] 55s Fetched 1121 kB in 0s (14.3 MB/s) 55s (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 ... 108975 files and directories currently installed.) 55s Preparing to unpack .../curl_8.12.1-2ubuntu1_amd64.deb ... 55s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 55s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_amd64.deb ... 55s Unpacking libcurl4t64:amd64 (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 55s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_amd64.deb ... 55s Unpacking libcurl3t64-gnutls:amd64 (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 55s Setting up libcurl4t64:amd64 (8.12.1-2ubuntu1) ... 55s Setting up libcurl3t64-gnutls:amd64 (8.12.1-2ubuntu1) ... 55s Setting up curl (8.12.1-2ubuntu1) ... 55s Processing triggers for man-db (2.13.0-1) ... 56s Processing triggers for libc-bin (2.40-4ubuntu1) ... 56s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 56s + /usr/lib/apt/apt-helper analyze-pattern ?true 56s + uname -r 56s + sed s/\./\\./g 56s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 56s + apt list ?obsolete 56s + tail -n+2 56s + cut -d/ -f1 56s + grep -v ^linux-.*6\.12\.0-15-generic.* 56s + true 56s + obsolete_pkgs= 56s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 56s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s Solving dependencies... 57s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s + grep -q trusty /etc/lsb-release 57s + [ ! -d /usr/share/doc/unattended-upgrades ] 57s + [ ! -d /usr/share/doc/lxd ] 57s + [ ! -d /usr/share/doc/lxd-client ] 57s + [ ! -d /usr/share/doc/snapd ] 57s + type iptables 57s + cat 57s + chmod 755 /etc/rc.local 57s + . /etc/rc.local 57s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 57s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 57s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 57s + uname -m 57s + [ x86_64 = ppc64le ] 57s + [ -d /run/systemd/system ] 57s + systemd-detect-virt --quiet --vm 57s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 57s + cat 57s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 57s + echo COMPRESS=lz4 57s autopkgtest [06:46:35]: upgrading testbed (apt dist-upgrade and autopurge) 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 58s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 58s Starting 2 pkgProblemResolver with broken count: 0 58s Done 58s Entering ResolveByKeep 59s 59s Calculating upgrade... 59s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Starting pkgProblemResolver with broken count: 0 60s Starting 2 pkgProblemResolver with broken count: 0 60s Done 60s Solving dependencies... 60s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 61s autopkgtest [06:46:39]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Tue Feb 4 16:02:16 UTC 2025 61s autopkgtest [06:46:39]: @@@@@@@@@@@@@@@@@@@@ apt-source cyrus-sasl2 64s Get:1 http://ftpmaster.internal/ubuntu plucky/main cyrus-sasl2 2.1.28+dfsg1-8build1 (dsc) [3435 B] 64s Get:2 http://ftpmaster.internal/ubuntu plucky/main cyrus-sasl2 2.1.28+dfsg1-8build1 (tar) [795 kB] 64s Get:3 http://ftpmaster.internal/ubuntu plucky/main cyrus-sasl2 2.1.28+dfsg1-8build1 (diff) [99.0 kB] 64s gpgv: Signature made Thu Feb 6 13:56:02 2025 UTC 64s gpgv: using RSA key 63EEFC3DE14D5146CE7F24BF34B8AD7D9529E793 64s gpgv: issuer "lena.voytek@canonical.com" 64s gpgv: Can't check signature: No public key 64s dpkg-source: warning: cannot verify inline signature for ./cyrus-sasl2_2.1.28+dfsg1-8build1.dsc: no acceptable signature found 64s autopkgtest [06:46:42]: testing package cyrus-sasl2 version 2.1.28+dfsg1-8build1 66s autopkgtest [06:46:44]: build not needed 68s autopkgtest [06:46:46]: test pluginviewer: preparing testbed 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 69s Starting pkgProblemResolver with broken count: 0 69s Starting 2 pkgProblemResolver with broken count: 0 69s Done 70s The following NEW packages will be installed: 70s db-util db5.3-util gcc-15-base:i386 libaudit1:i386 libc6:i386 70s libcap-ng0:i386 libcom-err2:i386 libcrypt1:i386 libdb5.3t64:i386 70s libgcc-s1:i386 libgssapi-krb5-2:i386 libk5crypto3:i386 libkeyutils1:i386 70s libkrb5-3:i386 libkrb5support0:i386 libldap2:i386 libmysqlclient24:i386 70s libpam0g:i386 libpq5:i386 libsasl2-2:i386 libsasl2-modules:i386 70s libsasl2-modules-db:i386 libsasl2-modules-gssapi-mit:i386 70s libsasl2-modules-ldap:i386 libsasl2-modules-otp:i386 70s libsasl2-modules-sql:i386 libsqlite3-0:i386 libssl3t64:i386 libstdc++6:i386 70s libzstd1:i386 mysql-common sasl2-bin:i386 zlib1g:i386 70s 0 upgraded, 33 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 11.3 MB of archives. 70s After this operation, 40.2 MB of additional disk space will be used. 70s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 db5.3-util amd64 5.3.28+dfsg2-9 [73.2 kB] 70s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 db-util all 1:5.3.21ubuntu2 [2412 B] 70s Get:3 http://ftpmaster.internal/ubuntu plucky/main i386 gcc-15-base i386 15-20250213-1ubuntu1 [53.2 kB] 70s Get:4 http://ftpmaster.internal/ubuntu plucky/main i386 libgcc-s1 i386 15-20250213-1ubuntu1 [93.8 kB] 70s Get:5 http://ftpmaster.internal/ubuntu plucky/main i386 libc6 i386 2.40-4ubuntu1 [3039 kB] 70s Get:6 http://ftpmaster.internal/ubuntu plucky/main i386 libdb5.3t64 i386 5.3.28+dfsg2-9 [815 kB] 70s Get:7 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules-db i386 2.1.28+dfsg1-8build1 [21.9 kB] 70s Get:8 http://ftpmaster.internal/ubuntu plucky/main i386 libzstd1 i386 1.5.6+dfsg-2 [293 kB] 70s Get:9 http://ftpmaster.internal/ubuntu plucky/main i386 zlib1g i386 1:1.3.dfsg+really1.3.1-1ubuntu1 [59.8 kB] 70s Get:10 http://ftpmaster.internal/ubuntu plucky/main i386 libssl3t64 i386 3.4.1-1ubuntu1 [2201 kB] 70s Get:11 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-2 i386 2.1.28+dfsg1-8build1 [61.9 kB] 70s Get:12 http://ftpmaster.internal/ubuntu plucky/main i386 libcrypt1 i386 1:4.4.38-1 [91.6 kB] 70s Get:13 http://ftpmaster.internal/ubuntu plucky/main i386 libkrb5support0 i386 1.21.3-4ubuntu1 [37.1 kB] 70s Get:14 http://ftpmaster.internal/ubuntu plucky/main i386 libcom-err2 i386 1.47.2-1ubuntu1 [27.2 kB] 70s Get:15 http://ftpmaster.internal/ubuntu plucky/main i386 libk5crypto3 i386 1.21.3-4ubuntu1 [86.7 kB] 70s Get:16 http://ftpmaster.internal/ubuntu plucky/main i386 libkeyutils1 i386 1.6.3-4ubuntu2 [10.4 kB] 70s Get:17 http://ftpmaster.internal/ubuntu plucky/main i386 libkrb5-3 i386 1.21.3-4ubuntu1 [388 kB] 70s Get:18 http://ftpmaster.internal/ubuntu plucky/main i386 libldap2 i386 2.6.9+dfsg-1~exp2ubuntu1 [216 kB] 70s Get:19 http://ftpmaster.internal/ubuntu plucky/main i386 libcap-ng0 i386 0.8.5-4 [15.8 kB] 70s Get:20 http://ftpmaster.internal/ubuntu plucky/main i386 libaudit1 i386 1:4.0.2-2ubuntu1 [52.1 kB] 70s Get:21 http://ftpmaster.internal/ubuntu plucky/main i386 libpam0g i386 1.5.3-7ubuntu4 [71.7 kB] 70s Get:22 http://ftpmaster.internal/ubuntu plucky/main i386 sasl2-bin i386 2.1.28+dfsg1-8build1 [104 kB] 70s Get:23 http://ftpmaster.internal/ubuntu plucky/main i386 libgssapi-krb5-2 i386 1.21.3-4ubuntu1 [156 kB] 70s Get:24 http://ftpmaster.internal/ubuntu plucky/main i386 libsqlite3-0 i386 3.46.1-1 [792 kB] 70s Get:25 http://ftpmaster.internal/ubuntu plucky/main i386 libstdc++6 i386 15-20250213-1ubuntu1 [873 kB] 70s Get:26 http://ftpmaster.internal/ubuntu plucky/main amd64 mysql-common all 5.8+1.1.1 [6800 B] 70s Get:27 http://ftpmaster.internal/ubuntu plucky/main i386 libmysqlclient24 i386 8.4.4-0ubuntu1 [1301 kB] 70s Get:28 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 libpq5 i386 17.4-1 [155 kB] 70s Get:29 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules i386 2.1.28+dfsg1-8build1 [75.7 kB] 70s Get:30 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules-gssapi-mit i386 2.1.28+dfsg1-8build1 [32.8 kB] 70s Get:31 http://ftpmaster.internal/ubuntu plucky/universe i386 libsasl2-modules-ldap i386 2.1.28+dfsg1-8build1 [16.9 kB] 70s Get:32 http://ftpmaster.internal/ubuntu plucky/universe i386 libsasl2-modules-otp i386 2.1.28+dfsg1-8build1 [31.2 kB] 70s Get:33 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules-sql i386 2.1.28+dfsg1-8build1 [20.4 kB] 70s Preconfiguring packages ... 70s Fetched 11.3 MB in 0s (36.7 MB/s) 70s Selecting previously unselected package db5.3-util. 70s (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 ... 108975 files and directories currently installed.) 70s Preparing to unpack .../00-db5.3-util_5.3.28+dfsg2-9_amd64.deb ... 70s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 70s Selecting previously unselected package db-util. 70s Preparing to unpack .../01-db-util_1%3a5.3.21ubuntu2_all.deb ... 70s Unpacking db-util (1:5.3.21ubuntu2) ... 70s Selecting previously unselected package gcc-15-base:i386. 70s Preparing to unpack .../02-gcc-15-base_15-20250213-1ubuntu1_i386.deb ... 70s Unpacking gcc-15-base:i386 (15-20250213-1ubuntu1) ... 70s Selecting previously unselected package libgcc-s1:i386. 70s Preparing to unpack .../03-libgcc-s1_15-20250213-1ubuntu1_i386.deb ... 70s Unpacking libgcc-s1:i386 (15-20250213-1ubuntu1) ... 70s Selecting previously unselected package libc6:i386. 70s Preparing to unpack .../04-libc6_2.40-4ubuntu1_i386.deb ... 71s Unpacking libc6:i386 (2.40-4ubuntu1) ... 71s Selecting previously unselected package libdb5.3t64:i386. 71s Preparing to unpack .../05-libdb5.3t64_5.3.28+dfsg2-9_i386.deb ... 71s Unpacking libdb5.3t64:i386 (5.3.28+dfsg2-9) ... 71s Selecting previously unselected package libsasl2-modules-db:i386. 71s Preparing to unpack .../06-libsasl2-modules-db_2.1.28+dfsg1-8build1_i386.deb ... 71s Unpacking libsasl2-modules-db:i386 (2.1.28+dfsg1-8build1) ... 71s Selecting previously unselected package libzstd1:i386. 71s Preparing to unpack .../07-libzstd1_1.5.6+dfsg-2_i386.deb ... 71s Unpacking libzstd1:i386 (1.5.6+dfsg-2) ... 71s Selecting previously unselected package zlib1g:i386. 71s Preparing to unpack .../08-zlib1g_1%3a1.3.dfsg+really1.3.1-1ubuntu1_i386.deb ... 71s Unpacking zlib1g:i386 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 71s Selecting previously unselected package libssl3t64:i386. 71s Preparing to unpack .../09-libssl3t64_3.4.1-1ubuntu1_i386.deb ... 71s Unpacking libssl3t64:i386 (3.4.1-1ubuntu1) ... 71s Selecting previously unselected package libsasl2-2:i386. 71s Preparing to unpack .../10-libsasl2-2_2.1.28+dfsg1-8build1_i386.deb ... 71s Unpacking libsasl2-2:i386 (2.1.28+dfsg1-8build1) ... 71s Selecting previously unselected package libcrypt1:i386. 71s Preparing to unpack .../11-libcrypt1_1%3a4.4.38-1_i386.deb ... 71s Unpacking libcrypt1:i386 (1:4.4.38-1) ... 71s Selecting previously unselected package libkrb5support0:i386. 71s Preparing to unpack .../12-libkrb5support0_1.21.3-4ubuntu1_i386.deb ... 71s Unpacking libkrb5support0:i386 (1.21.3-4ubuntu1) ... 71s Selecting previously unselected package libcom-err2:i386. 71s Preparing to unpack .../13-libcom-err2_1.47.2-1ubuntu1_i386.deb ... 71s Unpacking libcom-err2:i386 (1.47.2-1ubuntu1) ... 71s Selecting previously unselected package libk5crypto3:i386. 71s Preparing to unpack .../14-libk5crypto3_1.21.3-4ubuntu1_i386.deb ... 71s Unpacking libk5crypto3:i386 (1.21.3-4ubuntu1) ... 71s Selecting previously unselected package libkeyutils1:i386. 71s Preparing to unpack .../15-libkeyutils1_1.6.3-4ubuntu2_i386.deb ... 71s Unpacking libkeyutils1:i386 (1.6.3-4ubuntu2) ... 71s Selecting previously unselected package libkrb5-3:i386. 71s Preparing to unpack .../16-libkrb5-3_1.21.3-4ubuntu1_i386.deb ... 71s Unpacking libkrb5-3:i386 (1.21.3-4ubuntu1) ... 71s Selecting previously unselected package libldap2:i386. 71s Preparing to unpack .../17-libldap2_2.6.9+dfsg-1~exp2ubuntu1_i386.deb ... 71s Unpacking libldap2:i386 (2.6.9+dfsg-1~exp2ubuntu1) ... 71s Selecting previously unselected package libcap-ng0:i386. 71s Preparing to unpack .../18-libcap-ng0_0.8.5-4_i386.deb ... 71s Unpacking libcap-ng0:i386 (0.8.5-4) ... 71s Selecting previously unselected package libaudit1:i386. 71s Preparing to unpack .../19-libaudit1_1%3a4.0.2-2ubuntu1_i386.deb ... 71s Unpacking libaudit1:i386 (1:4.0.2-2ubuntu1) ... 71s Selecting previously unselected package libpam0g:i386. 71s Preparing to unpack .../20-libpam0g_1.5.3-7ubuntu4_i386.deb ... 71s Unpacking libpam0g:i386 (1.5.3-7ubuntu4) ... 71s Selecting previously unselected package sasl2-bin:i386. 71s Preparing to unpack .../21-sasl2-bin_2.1.28+dfsg1-8build1_i386.deb ... 71s Unpacking sasl2-bin:i386 (2.1.28+dfsg1-8build1) ... 71s Selecting previously unselected package libgssapi-krb5-2:i386. 71s Preparing to unpack .../22-libgssapi-krb5-2_1.21.3-4ubuntu1_i386.deb ... 71s Unpacking libgssapi-krb5-2:i386 (1.21.3-4ubuntu1) ... 71s Selecting previously unselected package libsqlite3-0:i386. 71s Preparing to unpack .../23-libsqlite3-0_3.46.1-1_i386.deb ... 71s Unpacking libsqlite3-0:i386 (3.46.1-1) ... 71s Selecting previously unselected package libstdc++6:i386. 71s Preparing to unpack .../24-libstdc++6_15-20250213-1ubuntu1_i386.deb ... 71s Unpacking libstdc++6:i386 (15-20250213-1ubuntu1) ... 71s Selecting previously unselected package mysql-common. 72s Preparing to unpack .../25-mysql-common_5.8+1.1.1_all.deb ... 72s Unpacking mysql-common (5.8+1.1.1) ... 72s Selecting previously unselected package libmysqlclient24:i386. 72s Preparing to unpack .../26-libmysqlclient24_8.4.4-0ubuntu1_i386.deb ... 72s Unpacking libmysqlclient24:i386 (8.4.4-0ubuntu1) ... 72s Selecting previously unselected package libpq5:i386. 72s Preparing to unpack .../27-libpq5_17.4-1_i386.deb ... 72s Unpacking libpq5:i386 (17.4-1) ... 72s Selecting previously unselected package libsasl2-modules:i386. 72s Preparing to unpack .../28-libsasl2-modules_2.1.28+dfsg1-8build1_i386.deb ... 72s Unpacking libsasl2-modules:i386 (2.1.28+dfsg1-8build1) ... 72s Selecting previously unselected package libsasl2-modules-gssapi-mit:i386. 72s Preparing to unpack .../29-libsasl2-modules-gssapi-mit_2.1.28+dfsg1-8build1_i386.deb ... 72s Unpacking libsasl2-modules-gssapi-mit:i386 (2.1.28+dfsg1-8build1) ... 72s Selecting previously unselected package libsasl2-modules-ldap:i386. 72s Preparing to unpack .../30-libsasl2-modules-ldap_2.1.28+dfsg1-8build1_i386.deb ... 72s Unpacking libsasl2-modules-ldap:i386 (2.1.28+dfsg1-8build1) ... 72s Selecting previously unselected package libsasl2-modules-otp:i386. 72s Preparing to unpack .../31-libsasl2-modules-otp_2.1.28+dfsg1-8build1_i386.deb ... 72s Unpacking libsasl2-modules-otp:i386 (2.1.28+dfsg1-8build1) ... 72s Selecting previously unselected package libsasl2-modules-sql:i386. 72s Preparing to unpack .../32-libsasl2-modules-sql_2.1.28+dfsg1-8build1_i386.deb ... 72s Unpacking libsasl2-modules-sql:i386 (2.1.28+dfsg1-8build1) ... 72s Setting up mysql-common (5.8+1.1.1) ... 72s update-alternatives: using /etc/mysql/my.cnf.fallback to provide /etc/mysql/my.cnf (my.cnf) in auto mode 72s Setting up db5.3-util (5.3.28+dfsg2-9) ... 72s Setting up gcc-15-base:i386 (15-20250213-1ubuntu1) ... 72s Setting up db-util (1:5.3.21ubuntu2) ... 72s Setting up libgcc-s1:i386 (15-20250213-1ubuntu1) ... 72s Setting up libc6:i386 (2.40-4ubuntu1) ... 72s Setting up libdb5.3t64:i386 (5.3.28+dfsg2-9) ... 72s Setting up libstdc++6:i386 (15-20250213-1ubuntu1) ... 72s Setting up libzstd1:i386 (1.5.6+dfsg-2) ... 72s Setting up libkeyutils1:i386 (1.6.3-4ubuntu2) ... 72s Setting up libsqlite3-0:i386 (3.46.1-1) ... 72s Setting up zlib1g:i386 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 72s Setting up libcrypt1:i386 (1:4.4.38-1) ... 72s Setting up libcom-err2:i386 (1.47.2-1ubuntu1) ... 72s Setting up libkrb5support0:i386 (1.21.3-4ubuntu1) ... 72s Setting up libsasl2-modules-db:i386 (2.1.28+dfsg1-8build1) ... 72s Setting up libcap-ng0:i386 (0.8.5-4) ... 72s Setting up libaudit1:i386 (1:4.0.2-2ubuntu1) ... 72s Setting up libk5crypto3:i386 (1.21.3-4ubuntu1) ... 72s Setting up libssl3t64:i386 (3.4.1-1ubuntu1) ... 72s Setting up libsasl2-modules:i386 (2.1.28+dfsg1-8build1) ... 72s Setting up libmysqlclient24:i386 (8.4.4-0ubuntu1) ... 72s Setting up libpam0g:i386 (1.5.3-7ubuntu4) ... 72s Setting up libsasl2-2:i386 (2.1.28+dfsg1-8build1) ... 72s Setting up libkrb5-3:i386 (1.21.3-4ubuntu1) ... 72s Setting up libldap2:i386 (2.6.9+dfsg-1~exp2ubuntu1) ... 72s Setting up libsasl2-modules-otp:i386 (2.1.28+dfsg1-8build1) ... 72s Setting up sasl2-bin:i386 (2.1.28+dfsg1-8build1) ... 72s ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored. 72s ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored. 73s saslauthd.service is a disabled or a static unit, not starting it. 73s Setting up libgssapi-krb5-2:i386 (1.21.3-4ubuntu1) ... 73s Setting up libsasl2-modules-ldap:i386 (2.1.28+dfsg1-8build1) ... 73s Setting up libsasl2-modules-gssapi-mit:i386 (2.1.28+dfsg1-8build1) ... 73s Setting up libpq5:i386 (17.4-1) ... 73s Setting up libsasl2-modules-sql:i386 (2.1.28+dfsg1-8build1) ... 73s Processing triggers for libc-bin (2.40-4ubuntu1) ... 73s Processing triggers for man-db (2.13.0-1) ... 74s Processing triggers for systemd (257.2-3ubuntu1) ... 75s autopkgtest [06:46:53]: testbed environment configured for cross-architecture building 75s autopkgtest [06:46:53]: test pluginviewer: [----------------------- 75s Checking mechanism SCRAM-SHA-512 75s Checking mechanism SCRAM-SHA-384 75s Checking mechanism SCRAM-SHA-256 75s Checking mechanism SCRAM-SHA-224 75s Checking mechanism SCRAM-SHA-1 75s Checking mechanism GS2-KRB5 75s Checking mechanism GS2-IAKERB 75s Checking mechanism GSS-SPNEGO 75s Checking mechanism GSSAPI 75s Checking mechanism DIGEST-MD5 75s Checking mechanism EXTERNAL 75s Checking mechanism OTP 75s Checking mechanism NTLM 75s Checking mechanism CRAM-MD5 75s Checking mechanism PLAIN 75s Checking mechanism LOGIN 75s Checking mechanism ANONYMOUS 75s autopkgtest [06:46:53]: test pluginviewer: -----------------------] 76s autopkgtest [06:46:54]: test pluginviewer: - - - - - - - - - - results - - - - - - - - - - 76s pluginviewer PASS 76s autopkgtest [06:46:54]: test saslauthd: preparing testbed 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Starting pkgProblemResolver with broken count: 0 76s Starting 2 pkgProblemResolver with broken count: 0 76s Done 77s The following NEW packages will be installed: 77s ldap-utils libargon2-1 libltdl7 libodbc2 slapd 77s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 77s Need to get 2056 kB of archives. 77s After this operation, 6932 kB of additional disk space will be used. 77s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 libargon2-1 amd64 0~20190702+dfsg-4build1 [20.8 kB] 77s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libltdl7 amd64 2.5.4-3build1 [46.2 kB] 77s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libodbc2 amd64 2.3.12-2ubuntu1 [186 kB] 77s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 slapd amd64 2.6.9+dfsg-1~exp2ubuntu1 [1648 kB] 77s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 ldap-utils amd64 2.6.9+dfsg-1~exp2ubuntu1 [154 kB] 77s Preconfiguring packages ... 77s Fetched 2056 kB in 0s (26.7 MB/s) 77s Selecting previously unselected package libargon2-1:amd64. 77s (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 ... 109473 files and directories currently installed.) 77s Preparing to unpack .../libargon2-1_0~20190702+dfsg-4build1_amd64.deb ... 77s Unpacking libargon2-1:amd64 (0~20190702+dfsg-4build1) ... 77s Selecting previously unselected package libltdl7:amd64. 77s Preparing to unpack .../libltdl7_2.5.4-3build1_amd64.deb ... 77s Unpacking libltdl7:amd64 (2.5.4-3build1) ... 77s Selecting previously unselected package libodbc2:amd64. 78s Preparing to unpack .../libodbc2_2.3.12-2ubuntu1_amd64.deb ... 78s Unpacking libodbc2:amd64 (2.3.12-2ubuntu1) ... 78s Selecting previously unselected package slapd. 78s Preparing to unpack .../slapd_2.6.9+dfsg-1~exp2ubuntu1_amd64.deb ... 78s Unpacking slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 78s Selecting previously unselected package ldap-utils. 78s Preparing to unpack .../ldap-utils_2.6.9+dfsg-1~exp2ubuntu1_amd64.deb ... 78s Unpacking ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 78s Setting up libargon2-1:amd64 (0~20190702+dfsg-4build1) ... 78s Setting up ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 78s Setting up libltdl7:amd64 (2.5.4-3build1) ... 78s Setting up libodbc2:amd64 (2.3.12-2ubuntu1) ... 78s Setting up slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 78s Creating new user openldap... done. 78s Creating initial configuration... done. 78s Creating LDAP directory... done. 79s Created symlink '/etc/systemd/system/multi-user.target.wants/slapd.service' → '/usr/lib/systemd/system/slapd.service'. 79s Processing triggers for ufw (0.36.2-9) ... 79s Processing triggers for man-db (2.13.0-1) ... 80s Processing triggers for libc-bin (2.40-4ubuntu1) ... 80s autopkgtest [06:46:58]: testbed environment configured for cross-architecture building 80s autopkgtest [06:46:58]: test saslauthd: [----------------------- 80s Setting up saslauthd with mecanism sasldb 80s Waiting for saslauthd restart . 80s Authentication of user user3049 with correct password should succeed... OK 80s Authentication of unknown user user3049notexist should fail... OK 80s Authentication of user user3049 with incorrect password should fail... OK 80s 80s Setting up saslauthd with mecanism pam 81s Waiting for saslauthd restart . 81s Authentication of user user3049 with correct password should succeed... FAIL 81s exit status: 255 81s output: 81s 0: NO "authentication failed" 81s ## Something failed, gathering logs 81s 81s ## saslauthd 81s Feb 22 06:46:51 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s Feb 22 06:46:51 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s Feb 22 06:46:51 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s Feb 22 06:46:51 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s Feb 22 06:46:58 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s Feb 22 06:46:58 autopkgtest systemd[1]: Starting saslauthd.service - SASL Authentication Daemon... 81s Feb 22 06:46:58 autopkgtest saslauthd[3102]: : master pid is: 3102 81s Feb 22 06:46:58 autopkgtest saslauthd[3102]: : listening on socket: /var/run/saslauthd/mux 81s Feb 22 06:46:58 autopkgtest systemd[1]: Started saslauthd.service - SASL Authentication Daemon. 81s Feb 22 06:46:58 autopkgtest saslauthd[3104]: : auth failure: [user=user3049notexist] [service=imap] [realm=] [mech=sasldb] [reason=Unknown] 81s Feb 22 06:46:58 autopkgtest saslauthd[3105]: : auth failure: [user=user3049] [service=imap] [realm=] [mech=sasldb] [reason=Unknown] 81s Feb 22 06:46:59 autopkgtest systemd[1]: Stopping saslauthd.service - SASL Authentication Daemon... 81s Feb 22 06:46:59 autopkgtest saslauthd[3102]: : master exited: 3102 81s Feb 22 06:46:59 autopkgtest systemd[1]: saslauthd.service: Deactivated successfully. 81s Feb 22 06:46:59 autopkgtest systemd[1]: Stopped saslauthd.service - SASL Authentication Daemon. 81s Feb 22 06:46:59 autopkgtest systemd[1]: Starting saslauthd.service - SASL Authentication Daemon... 81s Feb 22 06:46:59 autopkgtest saslauthd[3125]: : master pid is: 3125 81s Feb 22 06:46:59 autopkgtest saslauthd[3125]: : listening on socket: /var/run/saslauthd/mux 81s Feb 22 06:46:59 autopkgtest systemd[1]: Started saslauthd.service - SASL Authentication Daemon. 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_unix.so): /usr/lib/security/pam_unix.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_unix.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_deny.so): /usr/lib/security/pam_deny.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_deny.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_permit.so): /usr/lib/security/pam_permit.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_permit.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_cap.so): /usr/lib/security/pam_cap.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_cap.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_umask.so): /usr/lib/security/pam_umask.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_umask.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM unable to dlopen(pam_systemd.so): /usr/lib/security/pam_systemd.so: cannot open shared object file: No such file or directory 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: PAM adding faulty module: pam_systemd.so 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: DEBUG: auth_pam: pam_authenticate failed: Module is unknown 81s Feb 22 06:46:59 autopkgtest saslauthd[3126]: : auth failure: [user=user3049] [service=imap] [realm=] [mech=pam] [reason=PAM auth error] 81s 81s ## syslog 81s 2025-02-22T06:46:23.745784+00:00 autopkgtest systemd-networkd[717]: ens3: Link DOWN 81s 2025-02-22T06:46:23.745792+00:00 autopkgtest systemd-networkd[717]: ens3: Lost carrier 81s 2025-02-22T06:46:23.745796+00:00 autopkgtest systemd-networkd[717]: ens3: Configuring with /run/systemd/network/10-netplan-ens3.network. 81s 2025-02-22T06:46:23.745800+00:00 autopkgtest systemd-networkd[717]: ens3: Link UP 81s 2025-02-22T06:46:23.745805+00:00 autopkgtest systemd-networkd[717]: ens3: Gained carrier 81s 2025-02-22T06:46:23.745809+00:00 autopkgtest systemd-networkd[717]: ens3: DHCPv4 address 10.134.235.137/24, gateway 10.134.235.1 acquired from 10.134.235.1 81s 2025-02-22T06:46:23.745813+00:00 autopkgtest systemd-timesyncd[335]: Network configuration changed, trying to establish connection. 81s 2025-02-22T06:46:23.745820+00:00 autopkgtest systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. 81s 2025-02-22T06:46:23.745823+00:00 autopkgtest cloud-init[429]: 2025-02-22 06:46:22,987 - loggers.py[DEPRECATED]: Deprecated cloud-config provided: apt_update: Deprecated in version 22.2. Use **package_update** instead., apt_upgrade: Deprecated in version 22.2. Use **package_upgrade** instead. 81s 2025-02-22T06:46:23.745828+00:00 autopkgtest cloud-init[429]: 2025-02-22 06:46:22,988 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 81s 2025-02-22T06:46:23.745832+00:00 autopkgtest systemd-resolved[333]: System hostname changed to 'autopkgtest'. 81s 2025-02-22T06:46:23.745840+00:00 autopkgtest sh[720]: Completed socket interaction for boot stage network 81s 2025-02-22T06:46:23.745847+00:00 autopkgtest systemd[1]: Finished cloud-init-network.service - Cloud-init: Network Stage. 81s 2025-02-22T06:46:23.745850+00:00 autopkgtest systemd[1]: Reached target cloud-config.target - Cloud-config availability. 81s 2025-02-22T06:46:23.745854+00:00 autopkgtest systemd[1]: Reached target sysinit.target - System Initialization. 81s 2025-02-22T06:46:23.745859+00:00 autopkgtest systemd[1]: apport-autoreport.path - Process error reports when automatic reporting is enabled (file watch) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/apport/autoreport). 81s 2025-02-22T06:46:23.745863+00:00 autopkgtest systemd[1]: tpm-udev.path - Handle dynamically added tpm devices was skipped because of an unmet condition check (ConditionVirtualization=container). 81s 2025-02-22T06:46:23.745879+00:00 autopkgtest systemd[1]: apport-autoreport.timer - Process error reports when automatic reporting is enabled (timer based) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/apport/autoreport). 81s 2025-02-22T06:46:23.745884+00:00 autopkgtest systemd[1]: Started apt-daily.timer - Daily apt download activities. 81s 2025-02-22T06:46:23.745887+00:00 autopkgtest systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. 81s 2025-02-22T06:46:23.745891+00:00 autopkgtest systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. 81s 2025-02-22T06:46:23.745895+00:00 autopkgtest systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. 81s 2025-02-22T06:46:23.745900+00:00 autopkgtest systemd[1]: Started fstrim.timer - Discard unused filesystem blocks once a week. 81s 2025-02-22T06:46:23.745911+00:00 autopkgtest systemd[1]: Started fwupd-refresh.timer - Refresh fwupd metadata regularly. 81s 2025-02-22T06:46:23.745915+00:00 autopkgtest systemd[1]: Started logrotate.timer - Daily rotation of log files. 81s 2025-02-22T06:46:23.745919+00:00 autopkgtest systemd[1]: Started man-db.timer - Daily man-db regeneration. 81s 2025-02-22T06:46:23.745924+00:00 autopkgtest systemd[1]: Started motd-news.timer - Message of the Day. 81s 2025-02-22T06:46:23.745928+00:00 autopkgtest systemd[1]: Started sysstat-collect.timer - Run system activity accounting tool every 10 minutes. 81s 2025-02-22T06:46:23.745932+00:00 autopkgtest systemd[1]: Started sysstat-rotate.timer - Rotate daily system activity data file at midnight. 81s 2025-02-22T06:46:23.745943+00:00 autopkgtest systemd[1]: Started sysstat-summary.timer - Generate summary of yesterday's process accounting. 81s 2025-02-22T06:46:23.745947+00:00 autopkgtest systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. 81s 2025-02-22T06:46:23.745952+00:00 autopkgtest systemd[1]: ua-timer.timer - Ubuntu Pro Timer for running repeated jobs was skipped because of an unmet condition check (ConditionPathExists=/var/lib/ubuntu-advantage/private/machine-token.json). 81s 2025-02-22T06:46:23.745956+00:00 autopkgtest systemd[1]: Reached target boot-complete.target - Boot Completion Check. 81s 2025-02-22T06:46:23.745959+00:00 autopkgtest systemd[1]: Reached target paths.target - Path Units. 81s 2025-02-22T06:46:23.745963+00:00 autopkgtest systemd[1]: Reached target timers.target - Timer Units. 81s 2025-02-22T06:46:23.745974+00:00 autopkgtest systemd[1]: apport-forward.socket - Unix socket for apport crash forwarding was skipped because of an unmet condition check (ConditionVirtualization=container). 81s 2025-02-22T06:46:23.745978+00:00 autopkgtest systemd[1]: Listening on cloud-init-hotplugd.socket - cloud-init hotplug hook socket. 81s 2025-02-22T06:46:23.745982+00:00 autopkgtest systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. 81s 2025-02-22T06:46:23.745987+00:00 autopkgtest systemd[1]: Starting lxd-installer.socket - Helper to install lxd snap on demand... 81s 2025-02-22T06:46:23.745991+00:00 autopkgtest systemd[1]: Listening on ssh.socket - OpenBSD Secure Shell server socket. 81s 2025-02-22T06:46:23.746002+00:00 autopkgtest systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). 81s 2025-02-22T06:46:23.746006+00:00 autopkgtest systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). 81s 2025-02-22T06:46:23.746009+00:00 autopkgtest systemd[1]: Reached target ssh-access.target - SSH Access Available. 81s 2025-02-22T06:46:23.746013+00:00 autopkgtest systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. 81s 2025-02-22T06:46:23.746017+00:00 autopkgtest systemd[1]: Listening on uuidd.socket - UUID daemon activation socket. 81s 2025-02-22T06:46:23.746021+00:00 autopkgtest systemd[1]: systemd-pcrphase-sysinit.service - TPM PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746031+00:00 autopkgtest systemd[1]: Listening on lxd-installer.socket - Helper to install lxd snap on demand. 81s 2025-02-22T06:46:23.746035+00:00 autopkgtest systemd[1]: Reached target sockets.target - Socket Units. 81s 2025-02-22T06:46:23.746039+00:00 autopkgtest systemd[1]: Reached target basic.target - Basic System. 81s 2025-02-22T06:46:23.746044+00:00 autopkgtest systemd[1]: System is tainted: unmerged-bin 81s 2025-02-22T06:46:23.746048+00:00 autopkgtest systemd[1]: Starting apport.service - automatic crash report generation... 81s 2025-02-22T06:46:23.746051+00:00 autopkgtest systemd[1]: autopkgtest@hvc1.service - autopkgtest root shell on hvc1 was skipped because of an unmet condition check (ConditionPathExists=/dev/hvc1). 81s 2025-02-22T06:46:23.746062+00:00 autopkgtest systemd[1]: Started autopkgtest@ttyS1.service - autopkgtest root shell on ttyS1. 81s 2025-02-22T06:46:23.746067+00:00 autopkgtest systemd[1]: Started cron.service - Regular background program processing daemon. 81s 2025-02-22T06:46:23.746071+00:00 autopkgtest systemd[1]: Starting dbus.service - D-Bus System Message Bus... 81s 2025-02-22T06:46:23.746075+00:00 autopkgtest systemd[1]: Started dmesg.service - Save initial kernel messages after boot. 81s 2025-02-22T06:46:23.746079+00:00 autopkgtest (cron)[774]: cron.service: Referenced but unset environment variable evaluates to an empty string: EXTRA_OPTS 81s 2025-02-22T06:46:23.746083+00:00 autopkgtest cron[774]: (CRON) INFO (pidfile fd = 3) 81s 2025-02-22T06:46:23.746100+00:00 autopkgtest systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... 81s 2025-02-22T06:46:23.746103+00:00 autopkgtest systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). 81s 2025-02-22T06:46:23.746108+00:00 autopkgtest systemd[1]: Starting grub-common.service - Record successful boot for GRUB... 81s 2025-02-22T06:46:23.746111+00:00 autopkgtest cron[774]: (CRON) INFO (Running @reboot jobs) 81s 2025-02-22T06:46:23.746115+00:00 autopkgtest systemd[1]: networkd-dispatcher.service - Dispatcher daemon for systemd-networkd was skipped because no trigger condition checks were met. 81s 2025-02-22T06:46:23.746118+00:00 autopkgtest systemd[1]: Starting rng-tools-debian.service - Hardware RNG entropy gatherer daemon (Debian variant)... 81s 2025-02-22T06:46:23.746129+00:00 autopkgtest systemd[1]: Starting rsyslog.service - System Logging Service... 81s 2025-02-22T06:46:23.746133+00:00 autopkgtest systemd[1]: secureboot-db.service - Secure Boot updates for DB and DBX was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/db-d719b2cb-3d3a-4596-a3bc-dad00e67656f). 81s 2025-02-22T06:46:23.746137+00:00 autopkgtest systemd[1]: Starting sysstat.service - Resets System Activity Logs... 81s 2025-02-22T06:46:23.746141+00:00 autopkgtest systemd[1]: Starting systemd-logind.service - User Login Management... 81s 2025-02-22T06:46:23.746145+00:00 autopkgtest systemd[1]: systemd-pcrphase.service - TPM PCR Barrier (User) was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746157+00:00 autopkgtest systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... 81s 2025-02-22T06:46:23.746161+00:00 autopkgtest systemd[1]: ua-reboot-cmds.service - Ubuntu Pro reboot cmds was skipped because of an unmet condition check (ConditionPathExists=/var/lib/ubuntu-advantage/private/machine-token.json). 81s 2025-02-22T06:46:23.746165+00:00 autopkgtest systemd[1]: Starting udisks2.service - Disk Manager... 81s 2025-02-22T06:46:23.746168+00:00 autopkgtest systemd[1]: autopkgtest@ttyS1.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746173+00:00 autopkgtest systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. 81s 2025-02-22T06:46:23.746176+00:00 autopkgtest rng-tools-debian: not starting: /dev/hwrng is used by the kernel automatically 81s 2025-02-22T06:46:23.746188+00:00 autopkgtest systemd[1]: rng-tools-debian.service: Skipped due to 'exec-condition'. 81s 2025-02-22T06:46:23.746192+00:00 autopkgtest systemd[1]: Condition check resulted in rng-tools-debian.service - Hardware RNG entropy gatherer daemon (Debian variant) being skipped. 81s 2025-02-22T06:46:23.746196+00:00 autopkgtest dbus-daemon[775]: [system] AppArmor D-Bus mediation is enabled 81s 2025-02-22T06:46:23.746200+00:00 autopkgtest systemd[1]: Started dbus.service - D-Bus System Message Bus. 81s 2025-02-22T06:46:23.746204+00:00 autopkgtest systemd[1]: Finished sysstat.service - Resets System Activity Logs. 81s 2025-02-22T06:46:23.746208+00:00 autopkgtest udisksd[785]: udisks daemon version 2.10.1 starting 81s 2025-02-22T06:46:23.746218+00:00 autopkgtest systemd[1]: grub-common.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746222+00:00 autopkgtest systemd[1]: Finished grub-common.service - Record successful boot for GRUB. 81s 2025-02-22T06:46:23.746226+00:00 autopkgtest systemd[1]: Starting grub-initrd-fallback.service - GRUB failed boot detection... 81s 2025-02-22T06:46:23.746249+00:00 autopkgtest systemd[1]: Started systemd-logind.service - User Login Management. 81s 2025-02-22T06:46:23.746253+00:00 autopkgtest systemd[1]: e2scrub_reap.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746257+00:00 autopkgtest systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. 81s 2025-02-22T06:46:23.746261+00:00 autopkgtest systemd[1]: grub-initrd-fallback.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746265+00:00 autopkgtest systemd[1]: Finished grub-initrd-fallback.service - GRUB failed boot detection. 81s 2025-02-22T06:46:23.746269+00:00 autopkgtest systemd[1]: Started udisks2.service - Disk Manager. 81s 2025-02-22T06:46:23.746277+00:00 autopkgtest udisksd[785]: Acquired the name org.freedesktop.UDisks2 on the system message bus 81s 2025-02-22T06:46:23.745768+00:00 autopkgtest rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd. [v8.2412.0] 81s 2025-02-22T06:46:23.745857+00:00 autopkgtest systemd[1]: Started rsyslog.service - System Logging Service. 81s 2025-02-22T06:46:23.745897+00:00 autopkgtest rsyslogd: rsyslogd's groupid changed to 102 81s 2025-02-22T06:46:23.745925+00:00 autopkgtest rsyslogd: rsyslogd's userid changed to 102 81s 2025-02-22T06:46:23.745950+00:00 autopkgtest rsyslogd: [origin software="rsyslogd" swVersion="8.2412.0" x-pid="837" x-info="https://www.rsyslog.com"] start 81s 2025-02-22T06:46:23.745319+00:00 autopkgtest kernel: Loaded X.509 cert 'Build time autogenerated kernel key: c457b781cedaf63ca358a6b907d76d32ca3eb274' 81s 2025-02-22T06:46:23.746625+00:00 autopkgtest kernel: ima: Allocated hash algorithm: sha256 81s 2025-02-22T06:46:23.746626+00:00 autopkgtest kernel: ima: No architecture policies found 81s 2025-02-22T06:46:23.746626+00:00 autopkgtest kernel: evm: Initialising EVM extended attributes: 81s 2025-02-22T06:46:23.746627+00:00 autopkgtest kernel: evm: security.selinux 81s 2025-02-22T06:46:23.746633+00:00 autopkgtest kernel: evm: security.SMACK64 81s 2025-02-22T06:46:23.746634+00:00 autopkgtest kernel: evm: security.SMACK64EXEC 81s 2025-02-22T06:46:23.746634+00:00 autopkgtest kernel: evm: security.SMACK64TRANSMUTE 81s 2025-02-22T06:46:23.746635+00:00 autopkgtest kernel: evm: security.SMACK64MMAP 81s 2025-02-22T06:46:23.746635+00:00 autopkgtest kernel: evm: security.apparmor 81s 2025-02-22T06:46:23.746635+00:00 autopkgtest kernel: evm: security.ima 81s 2025-02-22T06:46:23.746640+00:00 autopkgtest kernel: evm: security.capability 81s 2025-02-22T06:46:23.746640+00:00 autopkgtest kernel: evm: HMAC attrs: 0x1 81s 2025-02-22T06:46:23.746640+00:00 autopkgtest kernel: PM: Magic number: 13:498:772 81s 2025-02-22T06:46:23.746641+00:00 autopkgtest kernel: RAS: Correctable Errors collector initialized. 81s 2025-02-22T06:46:23.746641+00:00 autopkgtest kernel: clk: Disabling unused clocks 81s 2025-02-22T06:46:23.746642+00:00 autopkgtest kernel: PM: genpd: Disabling unused power domains 81s 2025-02-22T06:46:23.746642+00:00 autopkgtest kernel: Freeing unused decrypted memory: 2028K 81s 2025-02-22T06:46:23.746646+00:00 autopkgtest kernel: Freeing unused kernel image (initmem) memory: 5044K 81s 2025-02-22T06:46:23.746647+00:00 autopkgtest kernel: Write protecting the kernel read-only data: 38912k 81s 2025-02-22T06:46:23.746647+00:00 autopkgtest kernel: Freeing unused kernel image (rodata/data gap) memory: 1024K 81s 2025-02-22T06:46:23.746647+00:00 autopkgtest kernel: x86/mm: Checked W+X mappings: passed, no W+X pages found. 81s 2025-02-22T06:46:23.746648+00:00 autopkgtest kernel: Run /init as init process 81s 2025-02-22T06:46:23.746648+00:00 autopkgtest kernel: with arguments: 81s 2025-02-22T06:46:23.746652+00:00 autopkgtest kernel: /init 81s 2025-02-22T06:46:23.746653+00:00 autopkgtest kernel: with environment: 81s 2025-02-22T06:46:23.746653+00:00 autopkgtest kernel: HOME=/ 81s 2025-02-22T06:46:23.746654+00:00 autopkgtest kernel: TERM=linux 81s 2025-02-22T06:46:23.746654+00:00 autopkgtest kernel: BOOT_IMAGE=/vmlinuz-6.12.0-15-generic 81s 2025-02-22T06:46:23.746654+00:00 autopkgtest kernel: virtio_net virtio0 ens3: renamed from eth0 81s 2025-02-22T06:46:23.746658+00:00 autopkgtest kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 81s 2025-02-22T06:46:23.746659+00:00 autopkgtest kernel: input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 81s 2025-02-22T06:46:23.746659+00:00 autopkgtest kernel: FDC 0 is a S82078B 81s 2025-02-22T06:46:23.746660+00:00 autopkgtest kernel: raid6: avx2x4 gen() 28000 MB/s 81s 2025-02-22T06:46:23.746660+00:00 autopkgtest kernel: raid6: avx2x2 gen() 26664 MB/s 81s 2025-02-22T06:46:23.746661+00:00 autopkgtest kernel: raid6: avx2x1 gen() 22604 MB/s 81s 2025-02-22T06:46:23.746661+00:00 autopkgtest kernel: raid6: using algorithm avx2x4 gen() 28000 MB/s 81s 2025-02-22T06:46:23.746665+00:00 autopkgtest kernel: raid6: .... xor() 7471 MB/s, rmw enabled 81s 2025-02-22T06:46:23.746666+00:00 autopkgtest kernel: raid6: using avx2x2 recovery algorithm 81s 2025-02-22T06:46:23.746666+00:00 autopkgtest kernel: xor: automatically using best checksumming function avx 81s 2025-02-22T06:46:23.746666+00:00 autopkgtest kernel: Btrfs loaded, zoned=yes, fsverity=yes 81s 2025-02-22T06:46:23.746667+00:00 autopkgtest kernel: EXT4-fs (vda1): orphan cleanup on readonly fs 81s 2025-02-22T06:46:23.746667+00:00 autopkgtest kernel: EXT4-fs (vda1): mounted filesystem c1d91cd9-c36e-400f-a528-e35795b2f626 ro with ordered data mode. Quota mode: none. 81s 2025-02-22T06:46:23.746671+00:00 autopkgtest kernel: systemd[1]: Inserted module 'autofs4' 81s 2025-02-22T06:46:23.746672+00:00 autopkgtest kernel: systemd[1]: systemd 257.2-3ubuntu1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE) 81s 2025-02-22T06:46:23.746672+00:00 autopkgtest kernel: systemd[1]: Detected virtualization kvm. 81s 2025-02-22T06:46:23.746673+00:00 autopkgtest kernel: systemd[1]: Detected architecture x86-64. 81s 2025-02-22T06:46:23.746673+00:00 autopkgtest kernel: systemd[1]: Hostname set to . 81s 2025-02-22T06:46:23.746674+00:00 autopkgtest kernel: systemd[1]: bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported. 81s 2025-02-22T06:46:23.746678+00:00 autopkgtest kernel: Guest personality initialized and is inactive 81s 2025-02-22T06:46:23.746678+00:00 autopkgtest kernel: VMCI host device registered (name=vmci, major=10, minor=122) 81s 2025-02-22T06:46:23.746678+00:00 autopkgtest kernel: Initialized host personality 81s 2025-02-22T06:46:23.746679+00:00 autopkgtest kernel: NET: Registered PF_VSOCK protocol family 81s 2025-02-22T06:46:23.746679+00:00 autopkgtest kernel: systemd[1]: Queued start job for default target graphical.target. 81s 2025-02-22T06:46:23.746680+00:00 autopkgtest kernel: systemd[1]: Created slice system-autopkgtest.slice - Slice /system/autopkgtest. 81s 2025-02-22T06:46:23.746684+00:00 autopkgtest kernel: systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. 81s 2025-02-22T06:46:23.746684+00:00 autopkgtest kernel: systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 81s 2025-02-22T06:46:23.746685+00:00 autopkgtest kernel: systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. 81s 2025-02-22T06:46:23.746685+00:00 autopkgtest kernel: systemd[1]: Created slice user.slice - User and Session Slice. 81s 2025-02-22T06:46:23.746685+00:00 autopkgtest kernel: systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. 81s 2025-02-22T06:46:23.746686+00:00 autopkgtest kernel: systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. 81s 2025-02-22T06:46:23.746690+00:00 autopkgtest kernel: systemd[1]: Expecting device dev-disk-by\x2dlabel-BOOT.device - /dev/disk/by-label/BOOT... 81s 2025-02-22T06:46:23.746691+00:00 autopkgtest kernel: systemd[1]: Expecting device dev-disk-by\x2dlabel-UEFI.device - /dev/disk/by-label/UEFI... 81s 2025-02-22T06:46:23.746691+00:00 autopkgtest kernel: systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... 81s 2025-02-22T06:46:23.746692+00:00 autopkgtest kernel: systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. 81s 2025-02-22T06:46:23.746692+00:00 autopkgtest kernel: systemd[1]: Reached target remote-fs.target - Remote File Systems. 81s 2025-02-22T06:46:23.746692+00:00 autopkgtest kernel: systemd[1]: Reached target slices.target - Slice Units. 81s 2025-02-22T06:46:23.746693+00:00 autopkgtest kernel: systemd[1]: Reached target swap.target - Swaps. 81s 2025-02-22T06:46:23.746697+00:00 autopkgtest kernel: systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. 81s 2025-02-22T06:46:23.746697+00:00 autopkgtest kernel: systemd[1]: Listening on syslog.socket - Syslog Socket. 81s 2025-02-22T06:46:23.746698+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. 81s 2025-02-22T06:46:23.746698+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. 81s 2025-02-22T06:46:23.746698+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. 81s 2025-02-22T06:46:23.746699+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). 81s 2025-02-22T06:46:23.746703+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-journald.socket - Journal Sockets. 81s 2025-02-22T06:46:23.746703+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. 81s 2025-02-22T06:46:23.746704+00:00 autopkgtest kernel: systemd[1]: systemd-pcrextend.socket - TPM PCR Measurements was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746704+00:00 autopkgtest kernel: systemd[1]: systemd-pcrlock.socket - Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746705+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. 81s 2025-02-22T06:46:23.746705+00:00 autopkgtest kernel: systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. 81s 2025-02-22T06:46:23.746709+00:00 autopkgtest kernel: systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... 81s 2025-02-22T06:46:23.746709+00:00 autopkgtest kernel: systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 81s 2025-02-22T06:46:23.746710+00:00 autopkgtest kernel: systemd[1]: Mounting run-lock.mount - Legacy Locks Directory /run/lock... 81s 2025-02-22T06:46:23.746710+00:00 autopkgtest kernel: systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... 81s 2025-02-22T06:46:23.746711+00:00 autopkgtest kernel: systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... 81s 2025-02-22T06:46:23.746711+00:00 autopkgtest kernel: systemd[1]: Starting systemd-journald.service - Journal Service... 81s 2025-02-22T06:46:23.746716+00:00 autopkgtest kernel: systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout... 81s 2025-02-22T06:46:23.746717+00:00 autopkgtest kernel: systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... 81s 2025-02-22T06:46:23.746718+00:00 autopkgtest kernel: systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... 81s 2025-02-22T06:46:23.746718+00:00 autopkgtest kernel: systemd[1]: Starting modprobe@dm_multipath.service - Load Kernel Module dm_multipath... 81s 2025-02-22T06:46:23.746719+00:00 autopkgtest kernel: systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... 81s 2025-02-22T06:46:23.746719+00:00 autopkgtest kernel: systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... 81s 2025-02-22T06:46:23.746720+00:00 autopkgtest kernel: systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... 81s 2025-02-22T06:46:23.746805+00:00 autopkgtest kernel: systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 81s 2025-02-22T06:46:23.746806+00:00 autopkgtest kernel: systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root). 81s 2025-02-22T06:46:23.746807+00:00 autopkgtest kernel: systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). 81s 2025-02-22T06:46:23.746808+00:00 autopkgtest kernel: systemd-journald[275]: Collecting audit messages is disabled. 81s 2025-02-22T06:46:23.746808+00:00 autopkgtest kernel: systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... 81s 2025-02-22T06:46:23.746814+00:00 autopkgtest kernel: systemd[1]: systemd-pcrmachine.service - TPM PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746815+00:00 autopkgtest kernel: systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... 81s 2025-02-22T06:46:23.746815+00:00 autopkgtest kernel: systemd[1]: systemd-tpm2-setup-early.service - Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 81s 2025-02-22T06:46:23.746816+00:00 autopkgtest kernel: systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... 81s 2025-02-22T06:46:23.746816+00:00 autopkgtest kernel: systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... 81s 2025-02-22T06:46:23.746817+00:00 autopkgtest kernel: systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. 81s 2025-02-22T06:46:23.746822+00:00 autopkgtest kernel: systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. 81s 2025-02-22T06:46:23.746823+00:00 autopkgtest kernel: systemd[1]: Mounted run-lock.mount - Legacy Locks Directory /run/lock. 81s 2025-02-22T06:46:23.746823+00:00 autopkgtest kernel: systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. 81s 2025-02-22T06:46:23.746824+00:00 autopkgtest kernel: systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. 81s 2025-02-22T06:46:23.746824+00:00 autopkgtest kernel: systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. 81s 2025-02-22T06:46:23.746824+00:00 autopkgtest kernel: systemd[1]: modprobe@drm.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746829+00:00 autopkgtest kernel: systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. 81s 2025-02-22T06:46:23.746830+00:00 autopkgtest kernel: systemd[1]: modprobe@configfs.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746830+00:00 autopkgtest kernel: systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. 81s 2025-02-22T06:46:23.746831+00:00 autopkgtest kernel: systemd[1]: modprobe@dm_multipath.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746831+00:00 autopkgtest kernel: systemd[1]: Finished modprobe@dm_multipath.service - Load Kernel Module dm_multipath. 81s 2025-02-22T06:46:23.746832+00:00 autopkgtest kernel: systemd[1]: modprobe@fuse.service: Deactivated successfully. 81s 2025-02-22T06:46:23.746832+00:00 autopkgtest kernel: systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. 81s 2025-02-22T06:46:23.746838+00:00 autopkgtest kernel: systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. 81s 2025-02-22T06:46:23.746838+00:00 autopkgtest kernel: systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... 81s 2025-02-22T06:46:23.746839+00:00 autopkgtest kernel: systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... 81s 2025-02-22T06:46:23.746839+00:00 autopkgtest kernel: EXT4-fs (vda1): re-mounted c1d91cd9-c36e-400f-a528-e35795b2f626 r/w. Quota mode: none. 81s 2025-02-22T06:46:23.746840+00:00 autopkgtest kernel: systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... 81s 2025-02-22T06:46:23.746840+00:00 autopkgtest kernel: systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... 81s 2025-02-22T06:46:23.746845+00:00 autopkgtest kernel: systemd[1]: Started systemd-journald.service - Journal Service. 81s 2025-02-22T06:46:23.746846+00:00 autopkgtest kernel: systemd-journald[275]: Received client request to flush runtime journal. 81s 2025-02-22T06:46:23.746847+00:00 autopkgtest kernel: cryptd: max_cpu_qlen set to 1000 81s 2025-02-22T06:46:23.746847+00:00 autopkgtest kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 81s 2025-02-22T06:46:23.746848+00:00 autopkgtest kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD 81s 2025-02-22T06:46:23.746848+00:00 autopkgtest kernel: AES CTR mode by8 optimization enabled 81s 2025-02-22T06:46:23.746853+00:00 autopkgtest kernel: EXT4-fs (vda13): mounted filesystem a114845e-6f1f-49a7-8044-b769724fdfd4 r/w with ordered data mode. Quota mode: none. 81s 2025-02-22T06:46:23.746854+00:00 autopkgtest kernel: 8021q: 802.1Q VLAN Support v1.8 81s 2025-02-22T06:46:23.746854+00:00 autopkgtest kernel: 8021q: adding VLAN 0 to HW filter on device ens3 81s 2025-02-22T06:46:23.746855+00:00 autopkgtest kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database 81s 2025-02-22T06:46:23.746855+00:00 autopkgtest kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' 81s 2025-02-22T06:46:23.746856+00:00 autopkgtest kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' 81s 2025-02-22T06:46:23.746856+00:00 autopkgtest kernel: kvm_amd: Nested Virtualization enabled 81s 2025-02-22T06:46:23.746861+00:00 autopkgtest kernel: kvm_amd: Nested Paging enabled 81s 2025-02-22T06:46:23.746862+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.447:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="Discord" pid=478 comm="apparmor_parser" 81s 2025-02-22T06:46:23.746863+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.448:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="1password" pid=477 comm="apparmor_parser" 81s 2025-02-22T06:46:23.746863+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.461:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="QtWebEngineProcess" pid=485 comm="apparmor_parser" 81s 2025-02-22T06:46:23.746864+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.464:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name=4D6F6E676F444220436F6D70617373 pid=483 comm="apparmor_parser" 81s 2025-02-22T06:46:23.746875+00:00 autopkgtest kernel: qxl 0000:00:02.0: vgaarb: deactivate vga console 81s 2025-02-22T06:46:23.746876+00:00 autopkgtest kernel: Console: switching to colour dummy device 80x25 81s 2025-02-22T06:46:23.746876+00:00 autopkgtest kernel: [drm] Device Version 0.0 81s 2025-02-22T06:46:23.746877+00:00 autopkgtest kernel: [drm] Compression level 0 log level 0 81s 2025-02-22T06:46:23.746877+00:00 autopkgtest kernel: [drm] 12286 io pages at offset 0x1000000 81s 2025-02-22T06:46:23.746877+00:00 autopkgtest kernel: [drm] 16777216 byte draw area at offset 0x0 81s 2025-02-22T06:46:23.746882+00:00 autopkgtest kernel: [drm] RAM header offset: 0x3ffe000 81s 2025-02-22T06:46:23.746883+00:00 autopkgtest kernel: [drm] qxl: 16M of VRAM memory size 81s 2025-02-22T06:46:23.747004+00:00 autopkgtest kernel: [drm] qxl: 63M of IO pages memory ready (VRAM domain) 81s 2025-02-22T06:46:23.747004+00:00 autopkgtest kernel: [drm] qxl: 64M of Surface memory size 81s 2025-02-22T06:46:23.747005+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.477:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="alsamixer" pid=488 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747005+00:00 autopkgtest kernel: [drm] slot 0 (main): base 0xf4000000, size 0x03ffe000 81s 2025-02-22T06:46:23.747012+00:00 autopkgtest kernel: [drm] slot 1 (surfaces): base 0xf8000000, size 0x04000000 81s 2025-02-22T06:46:23.747012+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.486:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="Xorg" pid=487 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747013+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.489:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="babeld" pid=491 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747013+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.490:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="balena-etcher" pid=496 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747014+00:00 autopkgtest kernel: [drm] Initialized qxl 0.1.0 for 0000:00:02.0 on minor 0 81s 2025-02-22T06:46:23.747014+00:00 autopkgtest kernel: fbcon: qxldrmfb (fb0) is primary device 81s 2025-02-22T06:46:23.747018+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.499:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="bfdd" pid=497 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747019+00:00 autopkgtest kernel: Console: switching to colour frame buffer device 128x48 81s 2025-02-22T06:46:23.747019+00:00 autopkgtest kernel: qxl 0000:00:02.0: [drm] fb0: qxldrmfb frame buffer device 81s 2025-02-22T06:46:23.747019+00:00 autopkgtest kernel: kauditd_printk_skb: 89 callbacks suppressed 81s 2025-02-22T06:46:23.747020+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.660:100): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-abort" pid=595 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747020+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.663:101): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-adduser" pid=596 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747024+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.664:102): apparmor="STATUS" operation="profile_load" profile="unconfined" name="rygel" pid=593 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747025+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.664:103): apparmor="STATUS" operation="profile_load" profile="unconfined" name="rygel//mx-extract" pid=593 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747025+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.665:104): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-apt" pid=597 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747036+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.666:105): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-checkpackages" pid=598 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747037+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.668:106): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-clean" pid=599 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747041+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.669:107): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-createchroot" pid=600 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747041+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.671:108): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-destroychroot" pid=601 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747042+00:00 autopkgtest kernel: audit: type=1400 audit(1740206764.672:109): apparmor="STATUS" operation="profile_load" profile="unconfined" name="sbuild-distupgrade" pid=602 comm="apparmor_parser" 81s 2025-02-22T06:46:23.747042+00:00 autopkgtest kernel: 8021q: adding VLAN 0 to HW filter on device ens3 81s 2025-02-22T06:46:23.747043+00:00 autopkgtest kernel: kauditd_printk_skb: 64 callbacks suppressed 81s 2025-02-22T06:46:23.747047+00:00 autopkgtest kernel: audit: type=1400 audit(1740206783.692:174): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="rsyslogd" pid=812 comm="apparmor_parser" 81s 2025-02-22T06:46:23.782021+00:00 autopkgtest systemd[1]: Finished apport.service - automatic crash report generation. 81s 2025-02-22T06:46:24.005826+00:00 autopkgtest systemd-networkd[717]: ens3: Gained IPv6LL 81s 2025-02-22T06:46:24.025064+00:00 autopkgtest systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. 81s 2025-02-22T06:46:24.027146+00:00 autopkgtest systemd[1]: Reached target network-online.target - Network is Online. 81s 2025-02-22T06:46:24.030256+00:00 autopkgtest systemd[1]: Starting cloud-config.service - Cloud-init: Config Stage... 81s 2025-02-22T06:46:24.031410+00:00 autopkgtest systemd[1]: pollinate.service - Pollinate to seed the pseudo random number generator was skipped because of an unmet condition check (ConditionPathExists=!/var/cache/pollinate/seeded). 81s 2025-02-22T06:46:24.034271+00:00 autopkgtest systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... 81s 2025-02-22T06:46:24.038717+00:00 autopkgtest systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... 81s 2025-02-22T06:46:24.039147+00:00 autopkgtest systemd[1]: ubuntu-advantage.service - Ubuntu Pro Background Auto Attach was skipped because no trigger condition checks were met. 81s 2025-02-22T06:46:24.092647+00:00 autopkgtest cloud-init[429]: Cloud-init v. 25.1-0ubuntu1 running 'modules:config' at Sat, 22 Feb 2025 06:46:24 +0000. Up 24.65 seconds. 81s 2025-02-22T06:46:24.104354+00:00 autopkgtest systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. 81s 2025-02-22T06:46:24.109954+00:00 autopkgtest cloud-init[429]: 2025-02-22 06:46:24,109 - lifecycle.py[DEPRECATED]: The following config key(s): ['apt_mirror', 'apt_preserve_sources_list'] is deprecated in 22.1 and scheduled to be removed in 27.1. 81s 2025-02-22T06:46:24.115296+00:00 autopkgtest cloud-init[429]: 2025-02-22 06:46:24,109 - lifecycle.py[DEPRECATED]: Support for combined old and new apt module keys is deprecated in 22.1 and scheduled to be removed in 27.1. 81s 2025-02-22T06:46:24.122268+00:00 autopkgtest systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... 81s 2025-02-22T06:46:24.122472+00:00 autopkgtest systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... 81s 2025-02-22T06:46:24.122687+00:00 autopkgtest systemd[1]: Started ssh.service - OpenBSD Secure Shell server. 81s 2025-02-22T06:46:24.129507+00:00 autopkgtest systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. 81s 2025-02-22T06:46:24.132595+00:00 autopkgtest systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. 81s 2025-02-22T06:46:24.142965+00:00 autopkgtest systemd[1]: Starting setvtrgb.service - Set console scheme... 81s 2025-02-22T06:46:24.149314+00:00 autopkgtest sh[850]: Completed socket interaction for boot stage config 81s 2025-02-22T06:46:24.151769+00:00 autopkgtest systemd[1]: Finished cloud-config.service - Cloud-init: Config Stage. 81s 2025-02-22T06:46:24.154165+00:00 autopkgtest systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. 81s 2025-02-22T06:46:24.157605+00:00 autopkgtest systemd[1]: Finished setvtrgb.service - Set console scheme. 81s 2025-02-22T06:46:24.159502+00:00 autopkgtest systemd[1]: Created slice system-getty.slice - Slice /system/getty. 81s 2025-02-22T06:46:24.162688+00:00 autopkgtest systemd[1]: Started getty@tty1.service - Getty on tty1. 81s 2025-02-22T06:46:24.162773+00:00 autopkgtest systemd[1]: Reached target getty.target - Login Prompts. 81s 2025-02-22T06:46:24.163093+00:00 autopkgtest systemd[1]: Reached target multi-user.target - Multi-User System. 81s 2025-02-22T06:46:24.163842+00:00 autopkgtest systemd[1]: Reached target graphical.target - Graphical Interface. 81s 2025-02-22T06:46:24.165903+00:00 autopkgtest systemd[1]: Starting cloud-final.service - Cloud-init: Final Stage... 81s 2025-02-22T06:46:24.231805+00:00 autopkgtest cloud-init[429]: Cloud-init v. 25.1-0ubuntu1 running 'modules:final' at Sat, 22 Feb 2025 06:46:24 +0000. Up 24.80 seconds. 81s 2025-02-22T06:46:24.299504+00:00 autopkgtest systemd[1]: Starting apt-news.service - Update APT News... 81s 2025-02-22T06:46:24.309640+00:00 autopkgtest systemd[1]: Starting esm-cache.service - Update the local ESM caches... 81s 2025-02-22T06:46:24.351566+00:00 autopkgtest cloud-init[429]: Get:1 http://ftpmaster.internal/ubuntu plucky InRelease [249 kB] 81s 2025-02-22T06:46:24.397457+00:00 autopkgtest cloud-init[429]: Get:2 http://ftpmaster.internal/ubuntu plucky-updates InRelease [110 kB] 81s 2025-02-22T06:46:24.398712+00:00 autopkgtest cloud-init[429]: Get:3 http://ftpmaster.internal/ubuntu plucky-security InRelease [110 kB] 81s 2025-02-22T06:46:24.475194+00:00 autopkgtest cloud-init[429]: Get:4 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB] 81s 2025-02-22T06:46:24.513396+00:00 autopkgtest systemd[1]: apt-news.service: Deactivated successfully. 81s 2025-02-22T06:46:24.514527+00:00 autopkgtest systemd[1]: Finished apt-news.service - Update APT News. 81s 2025-02-22T06:46:24.517006+00:00 autopkgtest systemd[1]: esm-cache.service: Deactivated successfully. 81s 2025-02-22T06:46:24.518558+00:00 autopkgtest systemd[1]: Finished esm-cache.service - Update the local ESM caches. 81s 2025-02-22T06:46:24.747548+00:00 autopkgtest cloud-init[429]: Get:5 http://ftpmaster.internal/ubuntu plucky/main Sources [1383 kB] 81s 2025-02-22T06:46:24.769631+00:00 autopkgtest cloud-init[429]: Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 Packages [1457 kB] 81s 2025-02-22T06:46:24.790924+00:00 autopkgtest cloud-init[429]: Get:7 http://ftpmaster.internal/ubuntu plucky/main i386 Packages [1093 kB] 81s 2025-02-22T06:46:24.799245+00:00 autopkgtest cloud-init[429]: Get:8 http://ftpmaster.internal/ubuntu plucky/universe amd64 Packages [16.2 MB] 81s 2025-02-22T06:46:24.846819+00:00 autopkgtest systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. 81s 2025-02-22T06:46:24.853433+00:00 autopkgtest systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... 81s 2025-02-22T06:46:24.876995+00:00 autopkgtest systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. 81s 2025-02-22T06:46:24.884243+00:00 autopkgtest systemd[1]: Starting user@1000.service - User Manager for UID 1000... 81s 2025-02-22T06:46:24.923896+00:00 autopkgtest cloud-init[429]: Get:9 http://ftpmaster.internal/ubuntu plucky/universe i386 Packages [8818 kB] 81s 2025-02-22T06:46:25.035639+00:00 autopkgtest systemd[928]: Queued start job for default target default.target. 81s 2025-02-22T06:46:25.055330+00:00 autopkgtest systemd[928]: Created slice app.slice - User Application Slice. 81s 2025-02-22T06:46:25.057855+00:00 autopkgtest systemd[928]: Started launchpadlib-cache-clean.timer - Clean up old files in the Launchpadlib cache. 81s 2025-02-22T06:46:25.058673+00:00 autopkgtest systemd[928]: Reached target paths.target - Paths. 81s 2025-02-22T06:46:25.059804+00:00 autopkgtest systemd[928]: Reached target timers.target - Timers. 81s 2025-02-22T06:46:25.060010+00:00 autopkgtest systemd[928]: Starting dbus.socket - D-Bus User Message Bus Socket... 81s 2025-02-22T06:46:25.060985+00:00 autopkgtest systemd[928]: Listening on dirmngr.socket - GnuPG network certificate management daemon. 81s 2025-02-22T06:46:25.061459+00:00 autopkgtest systemd[928]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). 81s 2025-02-22T06:46:25.062600+00:00 autopkgtest systemd[928]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). 81s 2025-02-22T06:46:25.063068+00:00 autopkgtest systemd[928]: Starting gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation)... 81s 2025-02-22T06:46:25.063143+00:00 autopkgtest systemd[928]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. 81s 2025-02-22T06:46:25.063330+00:00 autopkgtest systemd[928]: Listening on keyboxd.socket - GnuPG public key management service. 81s 2025-02-22T06:46:25.063439+00:00 autopkgtest systemd[928]: Listening on dbus.socket - D-Bus User Message Bus Socket. 81s 2025-02-22T06:46:25.095385+00:00 autopkgtest systemd[928]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). 81s 2025-02-22T06:46:25.095522+00:00 autopkgtest systemd[928]: Reached target sockets.target - Sockets. 81s 2025-02-22T06:46:25.096455+00:00 autopkgtest systemd[928]: Reached target basic.target - Basic System. 81s 2025-02-22T06:46:25.097730+00:00 autopkgtest systemd[928]: Reached target default.target - Main User Target. 81s 2025-02-22T06:46:25.097820+00:00 autopkgtest systemd[928]: Startup finished in 181ms. 81s 2025-02-22T06:46:25.097907+00:00 autopkgtest systemd[1]: Started user@1000.service - User Manager for UID 1000. 81s 2025-02-22T06:46:25.107518+00:00 autopkgtest systemd[1]: Started session-1.scope - Session 1 of User ubuntu. 81s 2025-02-22T06:46:25.207846+00:00 autopkgtest systemd[1]: session-1.scope: Deactivated successfully. 81s 2025-02-22T06:46:25.327066+00:00 autopkgtest systemd[1]: Started session-3.scope - Session 3 of User ubuntu. 81s 2025-02-22T06:46:25.603901+00:00 autopkgtest systemd-resolved[333]: Clock change detected. Flushing caches. 81s 2025-02-22T06:46:25.604114+00:00 autopkgtest systemd-timesyncd[335]: Contacted time server 185.125.190.56:123 (ntp.ubuntu.com). 81s 2025-02-22T06:46:25.604168+00:00 autopkgtest systemd-timesyncd[335]: Initial clock synchronization to Sat 2025-02-22 06:46:25.601684 UTC. 81s 2025-02-22T06:46:27.886775+00:00 autopkgtest cloud-init[429]: Fetched 50.4 MB in 4s (12.9 MB/s) 81s 2025-02-22T06:46:28.068181+00:00 autopkgtest systemd[1]: dmesg.service: Deactivated successfully. 81s 2025-02-22T06:46:28.768403+00:00 autopkgtest cloud-init[429]: Reading package lists... 81s 2025-02-22T06:46:28.809706+00:00 autopkgtest cloud-init: ############################################################# 81s 2025-02-22T06:46:28.811386+00:00 autopkgtest cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 81s 2025-02-22T06:46:28.817452+00:00 autopkgtest cloud-init: 256 SHA256:pFA/iMcziIp/08kWRNkl4On1VpSg1GCGcl1v/nLqFgI root@autopkgtest (ECDSA) 81s 2025-02-22T06:46:28.823256+00:00 autopkgtest cloud-init: 256 SHA256:p7TgX0ONgzUHH9G344dT1jSwrLfZ6y8jSoZ9P2NojUg root@autopkgtest (ED25519) 81s 2025-02-22T06:46:28.830451+00:00 autopkgtest cloud-init: 3072 SHA256:CeZyFRjE5vjcgldk0V6tSBqIKGrti0rr4qAhj1wDF8c root@autopkgtest (RSA) 81s 2025-02-22T06:46:28.832245+00:00 autopkgtest cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 81s 2025-02-22T06:46:28.833942+00:00 autopkgtest cloud-init: ############################################################# 81s 2025-02-22T06:46:28.849146+00:00 autopkgtest cloud-init[429]: Cloud-init v. 25.1-0ubuntu1 finished at Sat, 22 Feb 2025 06:46:28 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 29.91 seconds 81s 2025-02-22T06:46:28.853122+00:00 autopkgtest sh[891]: Completed socket interaction for boot stage final 81s 2025-02-22T06:46:28.853232+00:00 autopkgtest cloud-init[429]: Generating public/private rsa key pair. 81s 2025-02-22T06:46:28.853417+00:00 autopkgtest cloud-init[429]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 81s 2025-02-22T06:46:28.853673+00:00 autopkgtest cloud-init[429]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 81s 2025-02-22T06:46:28.853851+00:00 autopkgtest cloud-init[429]: The key fingerprint is: 81s 2025-02-22T06:46:28.853995+00:00 autopkgtest cloud-init[429]: SHA256:CeZyFRjE5vjcgldk0V6tSBqIKGrti0rr4qAhj1wDF8c root@autopkgtest 81s 2025-02-22T06:46:28.854147+00:00 autopkgtest cloud-init[429]: The key's randomart image is: 81s 2025-02-22T06:46:28.854286+00:00 autopkgtest cloud-init[429]: +---[RSA 3072]----+ 81s 2025-02-22T06:46:28.854432+00:00 autopkgtest cloud-init[429]: | . +o+oo . | 81s 2025-02-22T06:46:28.854765+00:00 autopkgtest cloud-init[429]: |. . o = +.o . . | 81s 2025-02-22T06:46:28.854904+00:00 autopkgtest cloud-init[429]: |.... Eoo.= o . | 81s 2025-02-22T06:46:28.855045+00:00 autopkgtest cloud-init[429]: |.. .+o.oo.o . | 81s 2025-02-22T06:46:28.855196+00:00 autopkgtest cloud-init[429]: |.....+ooS | 81s 2025-02-22T06:46:28.855350+00:00 autopkgtest cloud-init[429]: | o..o= . | 81s 2025-02-22T06:46:28.855494+00:00 autopkgtest cloud-init[429]: |+..o.. . | 81s 2025-02-22T06:46:28.855704+00:00 autopkgtest cloud-init[429]: |B=o.. | 81s 2025-02-22T06:46:28.855841+00:00 autopkgtest cloud-init[429]: |O=. | 81s 2025-02-22T06:46:28.856004+00:00 autopkgtest cloud-init[429]: +----[SHA256]-----+ 81s 2025-02-22T06:46:28.856158+00:00 autopkgtest cloud-init[429]: Generating public/private ecdsa key pair. 81s 2025-02-22T06:46:28.856301+00:00 autopkgtest cloud-init[429]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 81s 2025-02-22T06:46:28.856445+00:00 autopkgtest cloud-init[429]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 81s 2025-02-22T06:46:28.856673+00:00 autopkgtest cloud-init[429]: The key fingerprint is: 81s 2025-02-22T06:46:28.856801+00:00 autopkgtest cloud-init[429]: SHA256:pFA/iMcziIp/08kWRNkl4On1VpSg1GCGcl1v/nLqFgI root@autopkgtest 81s 2025-02-22T06:46:28.856947+00:00 autopkgtest cloud-init[429]: The key's randomart image is: 81s 2025-02-22T06:46:28.857096+00:00 autopkgtest cloud-init[429]: +---[ECDSA 256]---+ 81s 2025-02-22T06:46:28.857255+00:00 autopkgtest cloud-init[429]: | +**=+... | 81s 2025-02-22T06:46:28.857400+00:00 autopkgtest cloud-init[429]: | ..B+Boo.o. | 81s 2025-02-22T06:46:28.857540+00:00 autopkgtest cloud-init[429]: | . +oX * + | 81s 2025-02-22T06:46:28.857688+00:00 autopkgtest cloud-init[429]: |.. = *Eo + | 81s 2025-02-22T06:46:28.857839+00:00 autopkgtest cloud-init[429]: |o + S.o . | 81s 2025-02-22T06:46:28.857978+00:00 autopkgtest cloud-init[429]: | . o o .. .. | 81s 2025-02-22T06:46:28.858122+00:00 autopkgtest cloud-init[429]: | . o = ...o | 81s 2025-02-22T06:46:28.858267+00:00 autopkgtest cloud-init[429]: | . o .+ | 81s 2025-02-22T06:46:28.858411+00:00 autopkgtest cloud-init[429]: | oo | 81s 2025-02-22T06:46:28.858591+00:00 autopkgtest cloud-init[429]: +----[SHA256]-----+ 81s 2025-02-22T06:46:28.858720+00:00 autopkgtest cloud-init[429]: Generating public/private ed25519 key pair. 81s 2025-02-22T06:46:28.858870+00:00 autopkgtest cloud-init[429]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 81s 2025-02-22T06:46:28.859014+00:00 autopkgtest cloud-init[429]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 81s 2025-02-22T06:46:28.859155+00:00 autopkgtest cloud-init[429]: The key fingerprint is: 81s 2025-02-22T06:46:28.859300+00:00 autopkgtest cloud-init[429]: SHA256:p7TgX0ONgzUHH9G344dT1jSwrLfZ6y8jSoZ9P2NojUg root@autopkgtest 81s 2025-02-22T06:46:28.859445+00:00 autopkgtest cloud-init[429]: The key's randomart image is: 81s 2025-02-22T06:46:28.859665+00:00 autopkgtest cloud-init[429]: +--[ED25519 256]--+ 81s 2025-02-22T06:46:28.859815+00:00 autopkgtest cloud-init[429]: | . o+. | 81s 2025-02-22T06:46:28.859959+00:00 autopkgtest cloud-init[429]: | o..o.o| 81s 2025-02-22T06:46:28.860104+00:00 autopkgtest cloud-init[429]: | o oo o+| 81s 2025-02-22T06:46:28.860249+00:00 autopkgtest cloud-init[429]: | o =. o+| 81s 2025-02-22T06:46:28.860403+00:00 autopkgtest cloud-init[429]: | . S =....+.| 81s 2025-02-22T06:46:28.860538+00:00 autopkgtest cloud-init[429]: | . o BE.. *..| 81s 2025-02-22T06:46:28.860739+00:00 autopkgtest cloud-init[429]: | . +.*..* o.| 81s 2025-02-22T06:46:28.860870+00:00 autopkgtest cloud-init[429]: | . +.o=.B .| 81s 2025-02-22T06:46:28.861013+00:00 autopkgtest cloud-init[429]: | . .o ooBo| 81s 2025-02-22T06:46:28.861167+00:00 autopkgtest cloud-init[429]: +----[SHA256]-----+ 81s 2025-02-22T06:46:28.870302+00:00 autopkgtest systemd[1]: Finished cloud-final.service - Cloud-init: Final Stage. 81s 2025-02-22T06:46:28.870904+00:00 autopkgtest systemd[1]: Reached target cloud-init.target - Cloud-init target. 81s 2025-02-22T06:46:28.873866+00:00 autopkgtest systemd[1]: Startup finished in 3.097s (kernel) + 26.838s (userspace) = 29.936s. 81s 2025-02-22T06:46:28.921792+00:00 autopkgtest systemd[1]: cloud-init-main.service: Deactivated successfully. 81s 2025-02-22T06:46:28.922001+00:00 autopkgtest systemd[1]: cloud-init-main.service: Consumed 6.474s CPU time, 447.1M memory peak. 81s 2025-02-22T06:46:28.947372+00:00 autopkgtest systemd[1]: session-3.scope: Deactivated successfully. 81s 2025-02-22T06:46:29.051898+00:00 autopkgtest systemd[1]: Started session-4.scope - Session 4 of User ubuntu. 81s 2025-02-22T06:46:30.016731+00:00 autopkgtest systemd[1]: Starting apt-news.service - Update APT News... 81s 2025-02-22T06:46:30.020959+00:00 autopkgtest systemd[1]: Starting esm-cache.service - Update the local ESM caches... 81s 2025-02-22T06:46:30.239199+00:00 autopkgtest systemd[1]: esm-cache.service: Deactivated successfully. 81s 2025-02-22T06:46:30.239263+00:00 autopkgtest systemd[1]: Finished esm-cache.service - Update the local ESM caches. 81s 2025-02-22T06:46:30.255730+00:00 autopkgtest systemd[1]: apt-news.service: Deactivated successfully. 81s 2025-02-22T06:46:30.256096+00:00 autopkgtest systemd[1]: Finished apt-news.service - Update APT News. 81s 2025-02-22T06:46:33.526085+00:00 autopkgtest systemd[1]: systemd-fsckd.service: Deactivated successfully. 81s 2025-02-22T06:46:50.815842+00:00 autopkgtest systemd[1]: Reload requested from client PID 2384 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:50.816017+00:00 autopkgtest systemd[1]: Reloading... 81s 2025-02-22T06:46:51.041486+00:00 autopkgtest systemd[1]: Reloading finished in 225 ms. 81s 2025-02-22T06:46:51.101070+00:00 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s 2025-02-22T06:46:51.116304+00:00 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s 2025-02-22T06:46:51.210625+00:00 autopkgtest systemd[1]: Reload requested from client PID 2432 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:51.210842+00:00 autopkgtest systemd[1]: Reloading... 81s 2025-02-22T06:46:51.424371+00:00 autopkgtest systemd[1]: Reloading finished in 212 ms. 81s 2025-02-22T06:46:51.484637+00:00 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s 2025-02-22T06:46:51.498789+00:00 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s 2025-02-22T06:46:52.393718+00:00 autopkgtest systemd[1]: Reexecution requested from client PID 2482 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:52.396871+00:00 autopkgtest systemd[1]: Reexecuting. 81s 2025-02-22T06:46:52.454642+00:00 autopkgtest kernel: systemd[1]: systemd 257.2-3ubuntu1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE) 81s 2025-02-22T06:46:52.454664+00:00 autopkgtest kernel: systemd[1]: Detected virtualization kvm. 81s 2025-02-22T06:46:52.454665+00:00 autopkgtest kernel: systemd[1]: Detected architecture x86-64. 81s 2025-02-22T06:46:52.570706+00:00 autopkgtest kernel: systemd[1]: bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported. 81s 2025-02-22T06:46:52.763428+00:00 autopkgtest systemd[928]: Received SIGRTMIN+25 from PID 1 (systemd). 81s 2025-02-22T06:46:52.763946+00:00 autopkgtest systemd[1]: user@1000.service: Sent signal SIGRTMIN+25 to main process 928 (systemd) on client request. 81s 2025-02-22T06:46:52.765074+00:00 autopkgtest systemd[928]: Reexecuting. 81s 2025-02-22T06:46:52.773638+00:00 autopkgtest systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 81s 2025-02-22T06:46:52.773929+00:00 autopkgtest systemd[1]: Stopping systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... 81s 2025-02-22T06:46:52.774149+00:00 autopkgtest systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. 81s 2025-02-22T06:46:52.774188+00:00 autopkgtest systemd[1]: Stopped systemd-networkd-wait-online.service - Wait for Network to be Configured. 81s 2025-02-22T06:46:52.774231+00:00 autopkgtest systemd[1]: Stopping systemd-networkd-wait-online.service - Wait for Network to be Configured... 81s 2025-02-22T06:46:52.808732+00:00 autopkgtest systemd[1]: systemd-networkd-persistent-storage.service: Deactivated successfully. 81s 2025-02-22T06:46:52.808949+00:00 autopkgtest systemd[1]: Stopped systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. 81s 2025-02-22T06:46:52.809970+00:00 autopkgtest systemd[1]: Stopping systemd-networkd.service - Network Configuration... 81s 2025-02-22T06:46:52.819058+00:00 autopkgtest systemd[1]: systemd-networkd.service: Deactivated successfully. 81s 2025-02-22T06:46:52.819107+00:00 autopkgtest systemd[1]: Stopped systemd-networkd.service - Network Configuration. 81s 2025-02-22T06:46:52.823077+00:00 autopkgtest systemd[1]: Starting systemd-networkd.service - Network Configuration... 81s 2025-02-22T06:46:52.869529+00:00 autopkgtest systemd-networkd[2533]: /run/systemd/network/10-netplan-ens3.network: MTUBytes= in [Link] section and UseMTU= in [DHCP] section are set. Disabling UseMTU=. 81s 2025-02-22T06:46:52.871539+00:00 autopkgtest systemd-networkd[2533]: lo: Link UP 81s 2025-02-22T06:46:52.872652+00:00 autopkgtest systemd-networkd[2533]: lo: Gained carrier 81s 2025-02-22T06:46:52.872804+00:00 autopkgtest systemd-networkd[2533]: ens3: Link UP 81s 2025-02-22T06:46:52.872855+00:00 autopkgtest systemd-networkd[2533]: ens3: Gained carrier 81s 2025-02-22T06:46:52.872920+00:00 autopkgtest systemd-networkd[2533]: ens3: Gained IPv6LL 81s 2025-02-22T06:46:52.874123+00:00 autopkgtest systemd-networkd[2533]: ens3: Configuring with /run/systemd/network/10-netplan-ens3.network. 81s 2025-02-22T06:46:52.874433+00:00 autopkgtest systemd[1]: Started systemd-networkd.service - Network Configuration. 81s 2025-02-22T06:46:52.877994+00:00 autopkgtest systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... 81s 2025-02-22T06:46:52.879976+00:00 autopkgtest systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... 81s 2025-02-22T06:46:52.887879+00:00 autopkgtest kernel: systemd-journald[275]: Received SIGTERM from PID 1 (systemd). 81s 2025-02-22T06:46:52.887898+00:00 autopkgtest kernel: systemd[1]: Stopping systemd-journald.service - Journal Service... 81s 2025-02-22T06:46:52.900871+00:00 autopkgtest kernel: systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. 81s 2025-02-22T06:46:52.909240+00:00 autopkgtest kernel: systemd[1]: systemd-journald.service: Deactivated successfully. 81s 2025-02-22T06:46:52.909250+00:00 autopkgtest kernel: systemd[1]: Stopped systemd-journald.service - Journal Service. 81s 2025-02-22T06:46:52.914584+00:00 autopkgtest kernel: systemd[1]: Starting systemd-journald.service - Journal Service... 81s 2025-02-22T06:46:52.916582+00:00 autopkgtest kernel: systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. 81s 2025-02-22T06:46:52.939596+00:00 autopkgtest kernel: systemd-journald[2544]: Collecting audit messages is disabled. 81s 2025-02-22T06:46:52.944600+00:00 autopkgtest systemd-networkd[2533]: ens3: DHCPv4 address 10.134.235.137/24, gateway 10.134.235.1 acquired from 10.134.235.1 81s 2025-02-22T06:46:52.944648+00:00 autopkgtest kernel: systemd[1]: Started systemd-journald.service - Journal Service. 81s 2025-02-22T06:46:56.446929+00:00 autopkgtest addgroup[2800]: Selecting GID from range 100 to 999 ... 81s 2025-02-22T06:46:56.450070+00:00 autopkgtest addgroup[2800]: Adding group `openldap' (GID 109) ... 81s 2025-02-22T06:46:56.506637+00:00 autopkgtest adduser[2808]: Selecting UID from range 100 to 999 ... 81s 2025-02-22T06:46:56.509506+00:00 autopkgtest adduser[2808]: Adding system user `openldap' (UID 108) ... 81s 2025-02-22T06:46:56.512049+00:00 autopkgtest adduser[2808]: Adding new user `openldap' (UID 108) with group `openldap' ... 81s 2025-02-22T06:46:56.538104+00:00 autopkgtest adduser[2808]: Creating home directory `/var/lib/ldap' ... 81s 2025-02-22T06:46:56.639079+00:00 autopkgtest systemd[1]: Reload requested from client PID 2864 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:56.639792+00:00 autopkgtest systemd[1]: Reloading... 81s 2025-02-22T06:46:56.858966+00:00 autopkgtest systemd[1]: Reloading finished in 219 ms. 81s 2025-02-22T06:46:57.077612+00:00 autopkgtest systemd[1]: Reload requested from client PID 2915 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:57.077692+00:00 autopkgtest systemd[1]: Reloading... 81s 2025-02-22T06:46:57.292335+00:00 autopkgtest systemd[1]: Reloading finished in 213 ms. 81s 2025-02-22T06:46:57.345088+00:00 autopkgtest systemd[1]: Starting slapd.service - OpenLDAP Server Daemon... 81s 2025-02-22T06:46:57.366234+00:00 autopkgtest slapd[2962]: @(#) $OpenLDAP: slapd 2.6.9+dfsg-1~exp2ubuntu1 (Jan 10 2025 21:55:16) $#012#011Ubuntu Developers 81s 2025-02-22T06:46:57.373811+00:00 autopkgtest slapd[2962]: slapd starting 81s 2025-02-22T06:46:57.374356+00:00 autopkgtest systemd[1]: Started slapd.service - OpenLDAP Server Daemon. 81s 2025-02-22T06:46:58.618810+00:00 autopkgtest systemd[1]: Reload requested from client PID 3052 ('systemctl') (unit session-4.scope)... 81s 2025-02-22T06:46:58.620699+00:00 autopkgtest systemd[1]: Reloading... 81s 2025-02-22T06:46:58.831291+00:00 autopkgtest systemd[1]: Reloading finished in 211 ms. 81s 2025-02-22T06:46:58.884632+00:00 autopkgtest systemd[1]: /usr/lib/systemd/system/saslauthd.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/saslauthd/saslauthd.pid → /run/saslauthd/saslauthd.pid; please update the unit file accordingly. 81s 2025-02-22T06:46:58.887859+00:00 autopkgtest systemd[1]: Starting saslauthd.service - SASL Authentication Daemon... 81s 2025-02-22T06:46:58.920881+00:00 autopkgtest systemd[1]: Started saslauthd.service - SASL Authentication Daemon. 81s 2025-02-22T06:46:59.036751+00:00 autopkgtest systemd[1]: Stopping saslauthd.service - SASL Authentication Daemon... 81s 2025-02-22T06:46:59.039237+00:00 autopkgtest systemd[1]: saslauthd.service: Deactivated successfully. 81s 2025-02-22T06:46:59.039532+00:00 autopkgtest systemd[1]: Stopped saslauthd.service - SASL Authentication Daemon. 81s 2025-02-22T06:46:59.041861+00:00 autopkgtest systemd[1]: Starting saslauthd.service - SASL Authentication Daemon... 81s 2025-02-22T06:46:59.065723+00:00 autopkgtest systemd[1]: Started saslauthd.service - SASL Authentication Daemon. 81s 81s ## sasldb2 users 81s user3049@autopkgtest: userPassword 81s user3049@autopkgtest: cmusaslsecretOTP 81s 81s ## slapd 81s Feb 22 06:46:57 autopkgtest systemd[1]: Starting slapd.service - OpenLDAP Server Daemon... 81s Feb 22 06:46:57 autopkgtest slapd[2962]: @(#) $OpenLDAP: slapd 2.6.9+dfsg-1~exp2ubuntu1 (Jan 10 2025 21:55:16) $ 81s Ubuntu Developers 81s Feb 22 06:46:57 autopkgtest slapd[2962]: slapd starting 81s Feb 22 06:46:57 autopkgtest systemd[1]: Started slapd.service - OpenLDAP Server Daemon. 81s autopkgtest [06:46:59]: test saslauthd: -----------------------] 81s saslauthd FAIL non-zero exit status 1 81s autopkgtest [06:46:59]: test saslauthd: - - - - - - - - - - results - - - - - - - - - - 81s autopkgtest [06:46:59]: test gssapi: preparing testbed 81s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Starting pkgProblemResolver with broken count: 0 82s Starting 2 pkgProblemResolver with broken count: 0 82s Done 82s The following NEW packages will be installed: 82s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 82s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 82s libverto-libevent1t64 libverto1t64 83s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 83s Need to get 789 kB of archives. 83s After this operation, 2674 kB of additional disk space will be used. 83s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 krb5-config all 2.7 [22.0 kB] 83s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libgssrpc4t64 amd64 1.21.3-4ubuntu1 [58.1 kB] 83s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5clnt-mit12 amd64 1.21.3-4ubuntu1 [41.3 kB] 83s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 libkdb5-10t64 amd64 1.21.3-4ubuntu1 [41.8 kB] 83s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5srv-mit12 amd64 1.21.3-4ubuntu1 [55.5 kB] 83s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-user amd64 1.21.3-4ubuntu1 [111 kB] 83s Get:7 http://ftpmaster.internal/ubuntu plucky/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 83s Get:8 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto1t64 amd64 0.3.1-1.2ubuntu3 [10.5 kB] 83s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto-libevent1t64 amd64 0.3.1-1.2ubuntu3 [6424 B] 83s Get:10 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-kdc amd64 1.21.3-4ubuntu1 [197 kB] 83s Get:11 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-admin-server amd64 1.21.3-4ubuntu1 [100 kB] 83s Preconfiguring packages ... 83s Fetched 789 kB in 0s (6083 kB/s) 83s Selecting previously unselected package krb5-config. 83s (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 ... 109793 files and directories currently installed.) 83s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 83s Unpacking krb5-config (2.7) ... 83s Selecting previously unselected package libgssrpc4t64:amd64. 83s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu1_amd64.deb ... 83s Unpacking libgssrpc4t64:amd64 (1.21.3-4ubuntu1) ... 83s Selecting previously unselected package libkadm5clnt-mit12:amd64. 83s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu1_amd64.deb ... 83s Unpacking libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu1) ... 83s Selecting previously unselected package libkdb5-10t64:amd64. 83s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu1_amd64.deb ... 83s Unpacking libkdb5-10t64:amd64 (1.21.3-4ubuntu1) ... 83s Selecting previously unselected package libkadm5srv-mit12:amd64. 83s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu1_amd64.deb ... 83s Unpacking libkadm5srv-mit12:amd64 (1.21.3-4ubuntu1) ... 83s Selecting previously unselected package krb5-user. 83s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu1_amd64.deb ... 83s Unpacking krb5-user (1.21.3-4ubuntu1) ... 83s Selecting previously unselected package libevent-2.1-7t64:amd64. 83s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 83s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 83s Selecting previously unselected package libverto1t64:amd64. 83s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_amd64.deb ... 83s Unpacking libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 83s Selecting previously unselected package libverto-libevent1t64:amd64. 83s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_amd64.deb ... 83s Unpacking libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 84s Selecting previously unselected package krb5-kdc. 84s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu1_amd64.deb ... 84s Unpacking krb5-kdc (1.21.3-4ubuntu1) ... 84s Selecting previously unselected package krb5-admin-server. 84s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu1_amd64.deb ... 84s Unpacking krb5-admin-server (1.21.3-4ubuntu1) ... 84s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 84s Setting up libgssrpc4t64:amd64 (1.21.3-4ubuntu1) ... 84s Setting up krb5-config (2.7) ... 84s Setting up libkadm5clnt-mit12:amd64 (1.21.3-4ubuntu1) ... 84s Setting up libkdb5-10t64:amd64 (1.21.3-4ubuntu1) ... 84s Setting up libkadm5srv-mit12:amd64 (1.21.3-4ubuntu1) ... 84s Setting up krb5-user (1.21.3-4ubuntu1) ... 84s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 84s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 84s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 84s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 84s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 84s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 84s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 84s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 84s Setting up libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 84s Setting up libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 84s Setting up krb5-kdc (1.21.3-4ubuntu1) ... 84s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 85s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 85s Setting up krb5-admin-server (1.21.3-4ubuntu1) ... 85s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 86s Processing triggers for libc-bin (2.40-4ubuntu1) ... 86s Processing triggers for man-db (2.13.0-1) ... 87s autopkgtest [06:47:05]: testbed environment configured for cross-architecture building 87s autopkgtest [06:47:05]: test gssapi: [----------------------- 87s Setting up slapd 87s Backing up /etc/ldap/slapd.d in /var/backups/slapd-2.6.9+dfsg-1~exp2ubuntu1... done. 87s Moving old database directory to /var/backups: 87s - directory unknown... done. 87s Creating initial configuration... done. 87s Creating LDAP directory... done. 88s 88s Creating Kerberos realm and principals 88s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 88s master key name 'K/M@EXAMPLE.FAKE' 88s No policy specified for user3705@EXAMPLE.FAKE; defaulting to no policy 88s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 88s Principal "user3705@EXAMPLE.FAKE" created. 88s No policy specified for ldap/server.example.fake@EXAMPLE.FAKE; defaulting to no policy 88s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 88s Principal "ldap/server.example.fake@EXAMPLE.FAKE" created. 88s 88s Extracting keytab 88s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 88s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 88s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 88s 88s Obtaining kerberos ticket for user3705 88s Password for user3705@EXAMPLE.FAKE: 88s 88s Using ticket to authenticate with OpenLDAP 88s Testing mechanism GSSAPI 88s ldap_sasl_interactive_bind: Unknown authentication method (-6) 88s additional info: SASL(-4): no mechanism available: No worthy mechs found 88s autopkgtest [06:47:06]: test gssapi: -----------------------] 89s gssapi FAIL non-zero exit status 250 89s autopkgtest [06:47:07]: test gssapi: - - - - - - - - - - results - - - - - - - - - - 89s autopkgtest [06:47:07]: test shared-secret-mechs: preparing testbed 156s autopkgtest [06:48:14]: testbed dpkg architecture: amd64 156s autopkgtest [06:48:14]: testbed apt version: 2.9.30ubuntu1 157s autopkgtest [06:48:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 157s autopkgtest [06:48:15]: testbed release detected to be: plucky 157s autopkgtest [06:48:15]: updating testbed package index (apt update) 157s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 157s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 157s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 157s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 157s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.9 kB] 157s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.5 kB] 157s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [504 kB] 157s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 157s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [98.8 kB] 157s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [129 kB] 157s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [7984 B] 157s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2412 B] 157s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [223 kB] 157s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [453 kB] 157s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [20.9 kB] 157s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [8844 B] 157s Fetched 1656 kB in 0s (5925 kB/s) 158s Reading package lists... 158s + lsb_release --codename --short 158s + RELEASE=plucky 158s + cat 158s + [ plucky != trusty ] 158s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 158s Reading package lists... 159s Building dependency tree... 159s Reading state information... 159s Calculating upgrade... 159s Calculating upgrade... 159s The following packages will be upgraded: 159s curl libcurl3t64-gnutls libcurl4t64 159s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 159s Need to get 1121 kB of archives. 159s After this operation, 7168 B disk space will be freed. 159s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 curl amd64 8.12.1-2ubuntu1 [251 kB] 159s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libcurl4t64 amd64 8.12.1-2ubuntu1 [437 kB] 159s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libcurl3t64-gnutls amd64 8.12.1-2ubuntu1 [432 kB] 159s Fetched 1121 kB in 0s (19.3 MB/s) 160s (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 ... 108975 files and directories currently installed.) 160s Preparing to unpack .../curl_8.12.1-2ubuntu1_amd64.deb ... 160s Unpacking curl (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 160s Preparing to unpack .../libcurl4t64_8.12.1-2ubuntu1_amd64.deb ... 160s Unpacking libcurl4t64:amd64 (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 160s Preparing to unpack .../libcurl3t64-gnutls_8.12.1-2ubuntu1_amd64.deb ... 160s Unpacking libcurl3t64-gnutls:amd64 (8.12.1-2ubuntu1) over (8.12.0+git20250209.89ed161+ds-1ubuntu1) ... 160s Setting up libcurl4t64:amd64 (8.12.1-2ubuntu1) ... 160s Setting up libcurl3t64-gnutls:amd64 (8.12.1-2ubuntu1) ... 160s Setting up curl (8.12.1-2ubuntu1) ... 160s Processing triggers for man-db (2.13.0-1) ... 160s Processing triggers for libc-bin (2.40-4ubuntu1) ... 161s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 161s + /usr/lib/apt/apt-helper analyze-pattern ?true 161s + uname -r 161s + sed s/\./\\./g 161s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 161s + apt list ?obsolete 161s + tail -n+2 161s + cut -d/ -f1 161s + grep -v ^linux-.*6\.12\.0-15-generic.* 161s + true 161s + obsolete_pkgs= 161s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 161s Reading package lists... 161s Building dependency tree... 161s Reading state information... 162s Solving dependencies... 162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 162s + grep -q trusty /etc/lsb-release 162s + [ ! -d /usr/share/doc/unattended-upgrades ] 162s + [ ! -d /usr/share/doc/lxd ] 162s + [ ! -d /usr/share/doc/lxd-client ] 162s + [ ! -d /usr/share/doc/snapd ] 162s + type iptables 162s + cat 162s + chmod 755 /etc/rc.local 162s + . /etc/rc.local 162s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 162s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 162s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 162s + uname -m 162s + [ x86_64 = ppc64le ] 162s + [ -d /run/systemd/system ] 162s + systemd-detect-virt --quiet --vm 162s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 162s + cat 162s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 162s + echo COMPRESS=lz4 162s autopkgtest [06:48:20]: upgrading testbed (apt dist-upgrade and autopurge) 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 162s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 162s Starting 2 pkgProblemResolver with broken count: 0 162s Done 163s Entering ResolveByKeep 163s 163s Calculating upgrade... 164s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 164s Reading package lists... 164s Building dependency tree... 164s Reading state information... 164s Starting pkgProblemResolver with broken count: 0 164s Starting 2 pkgProblemResolver with broken count: 0 164s Done 164s Solving dependencies... 165s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 166s Reading package lists... 166s Building dependency tree... 166s Reading state information... 166s Starting pkgProblemResolver with broken count: 0 166s Starting 2 pkgProblemResolver with broken count: 0 166s Done 167s The following NEW packages will be installed: 167s db-util db5.3-util gcc-15-base:i386 ldap-utils libargon2-1 libaudit1:i386 167s libc6:i386 libcap-ng0:i386 libcom-err2:i386 libcrypt1:i386 libdb5.3t64:i386 167s libgcc-s1:i386 libk5crypto3:i386 libkeyutils1:i386 libkrb5-3:i386 167s libkrb5support0:i386 libldap2:i386 libltdl7 libodbc2 libpam0g:i386 167s libsasl2-2:i386 libsasl2-modules:i386 libsasl2-modules-db:i386 167s libssl3t64:i386 libzstd1:i386 sasl2-bin:i386 slapd zlib1g:i386 167s 0 upgraded, 28 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 9946 kB of archives. 167s After this operation, 33.9 MB of additional disk space will be used. 167s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 db5.3-util amd64 5.3.28+dfsg2-9 [73.2 kB] 167s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 db-util all 1:5.3.21ubuntu2 [2412 B] 167s Get:3 http://ftpmaster.internal/ubuntu plucky/main i386 gcc-15-base i386 15-20250213-1ubuntu1 [53.2 kB] 167s Get:4 http://ftpmaster.internal/ubuntu plucky/main i386 libgcc-s1 i386 15-20250213-1ubuntu1 [93.8 kB] 167s Get:5 http://ftpmaster.internal/ubuntu plucky/main i386 libc6 i386 2.40-4ubuntu1 [3039 kB] 167s Get:6 http://ftpmaster.internal/ubuntu plucky/main i386 libdb5.3t64 i386 5.3.28+dfsg2-9 [815 kB] 167s Get:7 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules-db i386 2.1.28+dfsg1-8build1 [21.9 kB] 167s Get:8 http://ftpmaster.internal/ubuntu plucky/main i386 libzstd1 i386 1.5.6+dfsg-2 [293 kB] 167s Get:9 http://ftpmaster.internal/ubuntu plucky/main i386 zlib1g i386 1:1.3.dfsg+really1.3.1-1ubuntu1 [59.8 kB] 167s Get:10 http://ftpmaster.internal/ubuntu plucky/main i386 libssl3t64 i386 3.4.1-1ubuntu1 [2201 kB] 167s Get:11 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-2 i386 2.1.28+dfsg1-8build1 [61.9 kB] 167s Get:12 http://ftpmaster.internal/ubuntu plucky/main i386 libcrypt1 i386 1:4.4.38-1 [91.6 kB] 167s Get:13 http://ftpmaster.internal/ubuntu plucky/main i386 libkrb5support0 i386 1.21.3-4ubuntu1 [37.1 kB] 167s Get:14 http://ftpmaster.internal/ubuntu plucky/main i386 libcom-err2 i386 1.47.2-1ubuntu1 [27.2 kB] 167s Get:15 http://ftpmaster.internal/ubuntu plucky/main i386 libk5crypto3 i386 1.21.3-4ubuntu1 [86.7 kB] 167s Get:16 http://ftpmaster.internal/ubuntu plucky/main i386 libkeyutils1 i386 1.6.3-4ubuntu2 [10.4 kB] 167s Get:17 http://ftpmaster.internal/ubuntu plucky/main i386 libkrb5-3 i386 1.21.3-4ubuntu1 [388 kB] 167s Get:18 http://ftpmaster.internal/ubuntu plucky/main i386 libldap2 i386 2.6.9+dfsg-1~exp2ubuntu1 [216 kB] 167s Get:19 http://ftpmaster.internal/ubuntu plucky/main i386 libcap-ng0 i386 0.8.5-4 [15.8 kB] 167s Get:20 http://ftpmaster.internal/ubuntu plucky/main i386 libaudit1 i386 1:4.0.2-2ubuntu1 [52.1 kB] 167s Get:21 http://ftpmaster.internal/ubuntu plucky/main i386 libpam0g i386 1.5.3-7ubuntu4 [71.7 kB] 167s Get:22 http://ftpmaster.internal/ubuntu plucky/main i386 sasl2-bin i386 2.1.28+dfsg1-8build1 [104 kB] 167s Get:23 http://ftpmaster.internal/ubuntu plucky/main amd64 libargon2-1 amd64 0~20190702+dfsg-4build1 [20.8 kB] 167s Get:24 http://ftpmaster.internal/ubuntu plucky/main amd64 libltdl7 amd64 2.5.4-3build1 [46.2 kB] 167s Get:25 http://ftpmaster.internal/ubuntu plucky/main amd64 libodbc2 amd64 2.3.12-2ubuntu1 [186 kB] 167s Get:26 http://ftpmaster.internal/ubuntu plucky/main amd64 slapd amd64 2.6.9+dfsg-1~exp2ubuntu1 [1648 kB] 167s Get:27 http://ftpmaster.internal/ubuntu plucky/main amd64 ldap-utils amd64 2.6.9+dfsg-1~exp2ubuntu1 [154 kB] 167s Get:28 http://ftpmaster.internal/ubuntu plucky/main i386 libsasl2-modules i386 2.1.28+dfsg1-8build1 [75.7 kB] 167s Preconfiguring packages ... 168s Fetched 9946 kB in 0s (36.9 MB/s) 168s Selecting previously unselected package db5.3-util. 168s (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 ... 108975 files and directories currently installed.) 168s Preparing to unpack .../00-db5.3-util_5.3.28+dfsg2-9_amd64.deb ... 168s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 168s Selecting previously unselected package db-util. 168s Preparing to unpack .../01-db-util_1%3a5.3.21ubuntu2_all.deb ... 168s Unpacking db-util (1:5.3.21ubuntu2) ... 168s Selecting previously unselected package gcc-15-base:i386. 168s Preparing to unpack .../02-gcc-15-base_15-20250213-1ubuntu1_i386.deb ... 168s Unpacking gcc-15-base:i386 (15-20250213-1ubuntu1) ... 168s Selecting previously unselected package libgcc-s1:i386. 168s Preparing to unpack .../03-libgcc-s1_15-20250213-1ubuntu1_i386.deb ... 168s Unpacking libgcc-s1:i386 (15-20250213-1ubuntu1) ... 168s Selecting previously unselected package libc6:i386. 168s Preparing to unpack .../04-libc6_2.40-4ubuntu1_i386.deb ... 168s Unpacking libc6:i386 (2.40-4ubuntu1) ... 168s Selecting previously unselected package libdb5.3t64:i386. 168s Preparing to unpack .../05-libdb5.3t64_5.3.28+dfsg2-9_i386.deb ... 168s Unpacking libdb5.3t64:i386 (5.3.28+dfsg2-9) ... 168s Selecting previously unselected package libsasl2-modules-db:i386. 168s Preparing to unpack .../06-libsasl2-modules-db_2.1.28+dfsg1-8build1_i386.deb ... 168s Unpacking libsasl2-modules-db:i386 (2.1.28+dfsg1-8build1) ... 168s Selecting previously unselected package libzstd1:i386. 168s Preparing to unpack .../07-libzstd1_1.5.6+dfsg-2_i386.deb ... 168s Unpacking libzstd1:i386 (1.5.6+dfsg-2) ... 168s Selecting previously unselected package zlib1g:i386. 168s Preparing to unpack .../08-zlib1g_1%3a1.3.dfsg+really1.3.1-1ubuntu1_i386.deb ... 168s Unpacking zlib1g:i386 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 168s Selecting previously unselected package libssl3t64:i386. 168s Preparing to unpack .../09-libssl3t64_3.4.1-1ubuntu1_i386.deb ... 168s Unpacking libssl3t64:i386 (3.4.1-1ubuntu1) ... 168s Selecting previously unselected package libsasl2-2:i386. 168s Preparing to unpack .../10-libsasl2-2_2.1.28+dfsg1-8build1_i386.deb ... 168s Unpacking libsasl2-2:i386 (2.1.28+dfsg1-8build1) ... 168s Selecting previously unselected package libcrypt1:i386. 168s Preparing to unpack .../11-libcrypt1_1%3a4.4.38-1_i386.deb ... 168s Unpacking libcrypt1:i386 (1:4.4.38-1) ... 168s Selecting previously unselected package libkrb5support0:i386. 168s Preparing to unpack .../12-libkrb5support0_1.21.3-4ubuntu1_i386.deb ... 168s Unpacking libkrb5support0:i386 (1.21.3-4ubuntu1) ... 168s Selecting previously unselected package libcom-err2:i386. 168s Preparing to unpack .../13-libcom-err2_1.47.2-1ubuntu1_i386.deb ... 168s Unpacking libcom-err2:i386 (1.47.2-1ubuntu1) ... 168s Selecting previously unselected package libk5crypto3:i386. 168s Preparing to unpack .../14-libk5crypto3_1.21.3-4ubuntu1_i386.deb ... 168s Unpacking libk5crypto3:i386 (1.21.3-4ubuntu1) ... 168s Selecting previously unselected package libkeyutils1:i386. 168s Preparing to unpack .../15-libkeyutils1_1.6.3-4ubuntu2_i386.deb ... 168s Unpacking libkeyutils1:i386 (1.6.3-4ubuntu2) ... 168s Selecting previously unselected package libkrb5-3:i386. 168s Preparing to unpack .../16-libkrb5-3_1.21.3-4ubuntu1_i386.deb ... 168s Unpacking libkrb5-3:i386 (1.21.3-4ubuntu1) ... 168s Selecting previously unselected package libldap2:i386. 169s Preparing to unpack .../17-libldap2_2.6.9+dfsg-1~exp2ubuntu1_i386.deb ... 169s Unpacking libldap2:i386 (2.6.9+dfsg-1~exp2ubuntu1) ... 169s Selecting previously unselected package libcap-ng0:i386. 169s Preparing to unpack .../18-libcap-ng0_0.8.5-4_i386.deb ... 169s Unpacking libcap-ng0:i386 (0.8.5-4) ... 169s Selecting previously unselected package libaudit1:i386. 169s Preparing to unpack .../19-libaudit1_1%3a4.0.2-2ubuntu1_i386.deb ... 169s Unpacking libaudit1:i386 (1:4.0.2-2ubuntu1) ... 169s Selecting previously unselected package libpam0g:i386. 169s Preparing to unpack .../20-libpam0g_1.5.3-7ubuntu4_i386.deb ... 169s Unpacking libpam0g:i386 (1.5.3-7ubuntu4) ... 169s Selecting previously unselected package sasl2-bin:i386. 169s Preparing to unpack .../21-sasl2-bin_2.1.28+dfsg1-8build1_i386.deb ... 169s Unpacking sasl2-bin:i386 (2.1.28+dfsg1-8build1) ... 169s Selecting previously unselected package libargon2-1:amd64. 169s Preparing to unpack .../22-libargon2-1_0~20190702+dfsg-4build1_amd64.deb ... 169s Unpacking libargon2-1:amd64 (0~20190702+dfsg-4build1) ... 169s Selecting previously unselected package libltdl7:amd64. 169s Preparing to unpack .../23-libltdl7_2.5.4-3build1_amd64.deb ... 169s Unpacking libltdl7:amd64 (2.5.4-3build1) ... 169s Selecting previously unselected package libodbc2:amd64. 169s Preparing to unpack .../24-libodbc2_2.3.12-2ubuntu1_amd64.deb ... 169s Unpacking libodbc2:amd64 (2.3.12-2ubuntu1) ... 169s Selecting previously unselected package slapd. 169s Preparing to unpack .../25-slapd_2.6.9+dfsg-1~exp2ubuntu1_amd64.deb ... 169s Unpacking slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 169s Selecting previously unselected package ldap-utils. 169s Preparing to unpack .../26-ldap-utils_2.6.9+dfsg-1~exp2ubuntu1_amd64.deb ... 169s Unpacking ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 169s Selecting previously unselected package libsasl2-modules:i386. 169s Preparing to unpack .../27-libsasl2-modules_2.1.28+dfsg1-8build1_i386.deb ... 169s Unpacking libsasl2-modules:i386 (2.1.28+dfsg1-8build1) ... 169s Setting up libargon2-1:amd64 (0~20190702+dfsg-4build1) ... 169s Setting up ldap-utils (2.6.9+dfsg-1~exp2ubuntu1) ... 169s Setting up db5.3-util (5.3.28+dfsg2-9) ... 169s Setting up libltdl7:amd64 (2.5.4-3build1) ... 169s Setting up libodbc2:amd64 (2.3.12-2ubuntu1) ... 169s Setting up gcc-15-base:i386 (15-20250213-1ubuntu1) ... 169s Setting up slapd (2.6.9+dfsg-1~exp2ubuntu1) ... 169s Creating new user openldap... done. 169s Creating initial configuration... done. 169s Creating LDAP directory... done. 170s Created symlink '/etc/systemd/system/multi-user.target.wants/slapd.service' → '/usr/lib/systemd/system/slapd.service'. 170s Setting up db-util (1:5.3.21ubuntu2) ... 170s Setting up libgcc-s1:i386 (15-20250213-1ubuntu1) ... 170s Setting up libc6:i386 (2.40-4ubuntu1) ... 170s Setting up libdb5.3t64:i386 (5.3.28+dfsg2-9) ... 170s Setting up libzstd1:i386 (1.5.6+dfsg-2) ... 170s Setting up libkeyutils1:i386 (1.6.3-4ubuntu2) ... 170s Setting up zlib1g:i386 (1:1.3.dfsg+really1.3.1-1ubuntu1) ... 170s Setting up libcrypt1:i386 (1:4.4.38-1) ... 170s Setting up libcom-err2:i386 (1.47.2-1ubuntu1) ... 170s Setting up libkrb5support0:i386 (1.21.3-4ubuntu1) ... 170s Setting up libsasl2-modules-db:i386 (2.1.28+dfsg1-8build1) ... 170s Setting up libcap-ng0:i386 (0.8.5-4) ... 170s Setting up libaudit1:i386 (1:4.0.2-2ubuntu1) ... 170s Setting up libk5crypto3:i386 (1.21.3-4ubuntu1) ... 170s Setting up libssl3t64:i386 (3.4.1-1ubuntu1) ... 170s Setting up libsasl2-modules:i386 (2.1.28+dfsg1-8build1) ... 170s Setting up libpam0g:i386 (1.5.3-7ubuntu4) ... 170s Setting up libsasl2-2:i386 (2.1.28+dfsg1-8build1) ... 170s Setting up libkrb5-3:i386 (1.21.3-4ubuntu1) ... 170s Setting up libldap2:i386 (2.6.9+dfsg-1~exp2ubuntu1) ... 170s Setting up sasl2-bin:i386 (2.1.28+dfsg1-8build1) ... 170s ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored. 170s ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored. 171s saslauthd.service is a disabled or a static unit, not starting it. 171s Processing triggers for ufw (0.36.2-9) ... 171s Processing triggers for man-db (2.13.0-1) ... 172s Processing triggers for libc-bin (2.40-4ubuntu1) ... 172s Processing triggers for systemd (257.2-3ubuntu1) ... 174s autopkgtest [06:48:32]: testbed environment configured for cross-architecture building 174s autopkgtest [06:48:32]: test shared-secret-mechs: [----------------------- 174s Setting up slapd 174s Backing up /etc/ldap/slapd.d in /var/backups/slapd-2.6.9+dfsg-1~exp2ubuntu1... done. 174s Moving old database directory to /var/backups: 174s - directory unknown... done. 174s Creating initial configuration... done. 174s Creating LDAP directory... done. 175s 175s Configuring slapd to use the sasldb auxprop plugin for SASL shared secret mechanisms 175s SASL/EXTERNAL authentication started 175s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 175s SASL SSF: 0 175s modifying entry "cn=config" 175s 175s Create test user user2385 in sasldb 175s 175s Testing shared secret mechanisms one by one 175s 175s Testing mechanism SCRAM-SHA-512 175s SASL/SCRAM-SHA-512 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=scram-sha-512,cn=auth 175s 175s Testing mechanism SCRAM-SHA-384 175s SASL/SCRAM-SHA-384 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=scram-sha-384,cn=auth 175s 175s Testing mechanism SCRAM-SHA-256 175s SASL/SCRAM-SHA-256 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=scram-sha-256,cn=auth 175s 175s Testing mechanism SCRAM-SHA-224 175s SASL/SCRAM-SHA-224 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=scram-sha-224,cn=auth 175s 175s Testing mechanism SCRAM-SHA-1 175s SASL/SCRAM-SHA-1 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=scram-sha-1,cn=auth 175s 175s Testing mechanism DIGEST-MD5 175s SASL/DIGEST-MD5 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 128 175s SASL data security layer installed. 175s dn:uid=user2385@example.fake,cn=digest-md5,cn=auth 175s 175s Testing mechanism CRAM-MD5 175s SASL/CRAM-MD5 authentication started 175s SASL username: user2385@example.fake 175s SASL SSF: 0 175s dn:uid=user2385@example.fake,cn=cram-md5,cn=auth 175s 176s autopkgtest [06:48:34]: test shared-secret-mechs: -----------------------] 176s shared-secret-mechs PASS 176s autopkgtest [06:48:34]: test shared-secret-mechs: - - - - - - - - - - results - - - - - - - - - - 176s autopkgtest [06:48:34]: @@@@@@@@@@@@@@@@@@@@ summary 176s pluginviewer PASS 176s saslauthd FAIL non-zero exit status 1 176s gssapi FAIL non-zero exit status 250 176s shared-secret-mechs PASS 192s nova [W] Skipping flock for amd64 192s Creating nova instance adt-plucky-i386-cyrus-sasl2-20250222-064538-juju-7f2275-prod-proposed-migration-environment-15-db946b34-7819-4f6e-a9af-65ce218466e2 from image adt/ubuntu-plucky-amd64-server-20250222.img (UUID aba6fb27-d21c-4f55-a27f-5f98cacbaba1)... 192s nova [W] Timed out waiting for caae0b75-2b06-4a2e-9011-b24dc0b73254 to get deleted. 192s nova [W] Skipping flock for amd64 192s Creating nova instance adt-plucky-i386-cyrus-sasl2-20250222-064538-juju-7f2275-prod-proposed-migration-environment-15-db946b34-7819-4f6e-a9af-65ce218466e2 from image adt/ubuntu-plucky-amd64-server-20250222.img (UUID aba6fb27-d21c-4f55-a27f-5f98cacbaba1)... 192s nova [W] Timed out waiting for 4c6f9b23-0612-4ce0-8a93-7c297dd250d2 to get deleted.