0s autopkgtest [13:13:59]: starting date and time: 2024-06-03 13:13:59+0000 0s autopkgtest [13:13:59]: git checkout: 930f72e5 setup-testbed: don't install 'Recommends' for 'linux-generic' 0s autopkgtest [13:13:59]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.qde2a7rb/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libxml2,src:libxml-libxml-perl --apt-upgrade autofs --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libxml2/2.12.7+dfsg-3 libxml-libxml-perl/2.0207+dfsg+really+2.0134-3' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-2.secgroup --name adt-oracular-s390x-autofs-20240603-131359-juju-7f2275-prod-proposed-migration-environment-2-60ba083b-60a1-479a-93d4-7532f12f64fd --image adt/ubuntu-oracular-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 183s autopkgtest [13:17:02]: testbed dpkg architecture: s390x 183s autopkgtest [13:17:02]: testbed apt version: 2.9.3 183s autopkgtest [13:17:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 184s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 185s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 185s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 185s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 185s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 185s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [62.1 kB] 185s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [305 kB] 185s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [1392 B] 185s Fetched 913 kB in 1s (823 kB/s) 185s Reading package lists... 189s Reading package lists... 189s Building dependency tree... 189s Reading state information... 190s Calculating upgrade... 190s The following packages will be upgraded: 190s ethtool libsensors-config libsensors5 libxml2 190s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 190s Need to get 925 kB of archives. 190s After this operation, 442 kB disk space will be freed. 190s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libxml2 s390x 2.12.7+dfsg-3 [663 kB] 190s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x ethtool s390x 1:6.9-1 [229 kB] 190s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x libsensors-config all 1:3.6.0-10 [5532 B] 190s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libsensors5 s390x 1:3.6.0-10 [26.9 kB] 191s Fetched 925 kB in 1s (1546 kB/s) 191s (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 ... 54601 files and directories currently installed.) 191s Preparing to unpack .../libxml2_2.12.7+dfsg-3_s390x.deb ... 191s Unpacking libxml2:s390x (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 191s Preparing to unpack .../ethtool_1%3a6.9-1_s390x.deb ... 191s Unpacking ethtool (1:6.9-1) over (1:6.7-1build1) ... 191s Preparing to unpack .../libsensors-config_1%3a3.6.0-10_all.deb ... 191s Unpacking libsensors-config (1:3.6.0-10) over (1:3.6.0-9build1) ... 191s Preparing to unpack .../libsensors5_1%3a3.6.0-10_s390x.deb ... 191s Unpacking libsensors5:s390x (1:3.6.0-10) over (1:3.6.0-9build1) ... 191s Setting up libsensors-config (1:3.6.0-10) ... 191s Setting up libsensors5:s390x (1:3.6.0-10) ... 191s Setting up libxml2:s390x (2.12.7+dfsg-3) ... 191s Setting up ethtool (1:6.9-1) ... 191s Processing triggers for man-db (2.12.1-1) ... 192s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 193s Reading package lists... 193s Building dependency tree... 193s Reading state information... 194s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 194s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 194s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 194s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 194s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 196s Reading package lists... 196s Reading package lists... 196s Building dependency tree... 196s Reading state information... 197s Calculating upgrade... 197s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 197s Reading package lists... 197s Building dependency tree... 197s Reading state information... 198s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 205s autopkgtest [13:17:24]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:14:26 UTC 2024 205s autopkgtest [13:17:24]: @@@@@@@@@@@@@@@@@@@@ apt-source autofs 217s Get:1 http://ftpmaster.internal/ubuntu oracular/main autofs 5.1.9-1ubuntu4 (dsc) [2552 B] 217s Get:2 http://ftpmaster.internal/ubuntu oracular/main autofs 5.1.9-1ubuntu4 (tar) [332 kB] 217s Get:3 http://ftpmaster.internal/ubuntu oracular/main autofs 5.1.9-1ubuntu4 (diff) [28.1 kB] 217s gpgv: Signature made Wed Apr 17 22:11:23 2024 UTC 217s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 217s gpgv: Can't check signature: No public key 217s dpkg-source: warning: cannot verify inline signature for ./autofs_5.1.9-1ubuntu4.dsc: no acceptable signature found 217s autopkgtest [13:17:36]: testing package autofs version 5.1.9-1ubuntu4 224s autopkgtest [13:17:43]: build not needed 229s autopkgtest [13:17:48]: test smb-mount: preparing testbed 232s Reading package lists... 232s Building dependency tree... 232s Reading state information... 232s Starting pkgProblemResolver with broken count: 0 232s Starting 2 pkgProblemResolver with broken count: 0 232s Done 233s The following additional packages will be installed: 233s autofs autofs-hesiod autofs-ldap cifs-utils libavahi-client3 233s libavahi-common-data libavahi-common3 libcups2t64 libhesiod0 libidn12 233s libldb2 libnsl2 libpython3.12t64 libsmbclient0 libtalloc2 libtdb1 233s libtevent0t64 libwbclient0 python3-dnspython python3-ldb python3-markdown 233s python3-samba python3-talloc python3-tdb samba samba-common samba-common-bin 233s samba-libs smbclient tdb-tools 233s Suggested packages: 233s winbind cups-common python3-trio python3-aioquic python3-h2 python3-httpx 233s python3-httpcore python-markdown-doc bind9 bind9utils ctdb ldb-tools ntp 233s | chrony heimdal-clients 233s Recommended packages: 233s nfs-common keyutils python3-gpg attr samba-dsdb-modules samba-vfs-modules 233s samba-ad-provision 233s The following NEW packages will be installed: 233s autofs autofs-hesiod autofs-ldap autopkgtest-satdep cifs-utils 233s libavahi-client3 libavahi-common-data libavahi-common3 libcups2t64 233s libhesiod0 libidn12 libldb2 libnsl2 libpython3.12t64 libsmbclient0 233s libtalloc2 libtdb1 libtevent0t64 libwbclient0 python3-dnspython python3-ldb 233s python3-markdown python3-samba python3-talloc python3-tdb samba samba-common 233s samba-common-bin samba-libs smbclient tdb-tools 233s 0 upgraded, 31 newly installed, 0 to remove and 0 not upgraded. 233s Need to get 16.3 MB/16.3 MB of archives. 233s After this operation, 72.5 MB of additional disk space will be used. 233s Get:1 /tmp/autopkgtest.ioZNtw/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B] 233s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x python3-dnspython all 2.6.1-1ubuntu1 [163 kB] 233s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x libtalloc2 s390x 2.4.2-1build2 [28.4 kB] 233s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libtdb1 s390x 1.4.10-1build1 [50.0 kB] 233s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x libtevent0t64 s390x 0.16.1-2build1 [43.4 kB] 233s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x libldb2 s390x 2:2.8.0+samba4.19.5+dfsg-4ubuntu9 [192 kB] 233s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x libpython3.12t64 s390x 3.12.3-1 [2530 kB] 234s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x python3-ldb s390x 2:2.8.0+samba4.19.5+dfsg-4ubuntu9 [42.3 kB] 234s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x python3-tdb s390x 1.4.10-1build1 [15.9 kB] 234s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x python3-markdown all 3.6-1 [73.4 kB] 234s Get:11 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-common-data s390x 0.8-13ubuntu6 [29.7 kB] 234s Get:12 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-common3 s390x 0.8-13ubuntu6 [24.1 kB] 234s Get:13 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-client3 s390x 0.8-13ubuntu6 [27.2 kB] 234s Get:14 http://ftpmaster.internal/ubuntu oracular/main s390x libwbclient0 s390x 2:4.19.5+dfsg-4ubuntu9 [71.3 kB] 234s Get:15 http://ftpmaster.internal/ubuntu oracular/main s390x samba-libs s390x 2:4.19.5+dfsg-4ubuntu9 [6231 kB] 235s Get:16 http://ftpmaster.internal/ubuntu oracular/main s390x python3-talloc s390x 2.4.2-1build2 [12.9 kB] 235s Get:17 http://ftpmaster.internal/ubuntu oracular/main s390x python3-samba s390x 2:4.19.5+dfsg-4ubuntu9 [2952 kB] 235s Get:18 http://ftpmaster.internal/ubuntu oracular/main s390x samba-common all 2:4.19.5+dfsg-4ubuntu9 [64.1 kB] 235s Get:19 http://ftpmaster.internal/ubuntu oracular/main s390x libcups2t64 s390x 2.4.7-1.2ubuntu7 [282 kB] 235s Get:20 http://ftpmaster.internal/ubuntu oracular/main s390x samba-common-bin s390x 2:4.19.5+dfsg-4ubuntu9 [1301 kB] 235s Get:21 http://ftpmaster.internal/ubuntu oracular/main s390x tdb-tools s390x 1.4.10-1build1 [26.5 kB] 235s Get:22 http://ftpmaster.internal/ubuntu oracular/main s390x samba s390x 2:4.19.5+dfsg-4ubuntu9 [1014 kB] 236s Get:23 http://ftpmaster.internal/ubuntu oracular/main s390x libnsl2 s390x 1.3.0-3build3 [44.1 kB] 236s Get:24 http://ftpmaster.internal/ubuntu oracular/main s390x autofs s390x 5.1.9-1ubuntu4 [294 kB] 236s Get:25 http://ftpmaster.internal/ubuntu oracular/main s390x cifs-utils s390x 2:7.0-2build1 [98.2 kB] 236s Get:26 http://ftpmaster.internal/ubuntu oracular/main s390x libidn12 s390x 1.42-2 [58.5 kB] 236s Get:27 http://ftpmaster.internal/ubuntu oracular/main s390x libsmbclient0 s390x 2:4.19.5+dfsg-4ubuntu9 [65.0 kB] 236s Get:28 http://ftpmaster.internal/ubuntu oracular/main s390x smbclient s390x 2:4.19.5+dfsg-4ubuntu9 [494 kB] 236s Get:29 http://ftpmaster.internal/ubuntu oracular/universe s390x libhesiod0 s390x 3.2.1-4build1 [23.5 kB] 236s Get:30 http://ftpmaster.internal/ubuntu oracular/universe s390x autofs-hesiod s390x 5.1.9-1ubuntu4 [10.6 kB] 236s Get:31 http://ftpmaster.internal/ubuntu oracular/universe s390x autofs-ldap s390x 5.1.9-1ubuntu4 [44.4 kB] 236s Preconfiguring packages ... 236s Fetched 16.3 MB in 3s (5789 kB/s) 236s Selecting previously unselected package python3-dnspython. 236s (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 ... 54601 files and directories currently installed.) 236s Preparing to unpack .../00-python3-dnspython_2.6.1-1ubuntu1_all.deb ... 236s Unpacking python3-dnspython (2.6.1-1ubuntu1) ... 236s Selecting previously unselected package libtalloc2:s390x. 236s Preparing to unpack .../01-libtalloc2_2.4.2-1build2_s390x.deb ... 236s Unpacking libtalloc2:s390x (2.4.2-1build2) ... 236s Selecting previously unselected package libtdb1:s390x. 236s Preparing to unpack .../02-libtdb1_1.4.10-1build1_s390x.deb ... 236s Unpacking libtdb1:s390x (1.4.10-1build1) ... 236s Selecting previously unselected package libtevent0t64:s390x. 236s Preparing to unpack .../03-libtevent0t64_0.16.1-2build1_s390x.deb ... 236s Unpacking libtevent0t64:s390x (0.16.1-2build1) ... 236s Selecting previously unselected package libldb2:s390x. 236s Preparing to unpack .../04-libldb2_2%3a2.8.0+samba4.19.5+dfsg-4ubuntu9_s390x.deb ... 236s Unpacking libldb2:s390x (2:2.8.0+samba4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package libpython3.12t64:s390x. 237s Preparing to unpack .../05-libpython3.12t64_3.12.3-1_s390x.deb ... 237s Unpacking libpython3.12t64:s390x (3.12.3-1) ... 237s Selecting previously unselected package python3-ldb. 237s Preparing to unpack .../06-python3-ldb_2%3a2.8.0+samba4.19.5+dfsg-4ubuntu9_s390x.deb ... 237s Unpacking python3-ldb (2:2.8.0+samba4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package python3-tdb. 237s Preparing to unpack .../07-python3-tdb_1.4.10-1build1_s390x.deb ... 237s Unpacking python3-tdb (1.4.10-1build1) ... 237s Selecting previously unselected package python3-markdown. 237s Preparing to unpack .../08-python3-markdown_3.6-1_all.deb ... 237s Unpacking python3-markdown (3.6-1) ... 237s Selecting previously unselected package libavahi-common-data:s390x. 237s Preparing to unpack .../09-libavahi-common-data_0.8-13ubuntu6_s390x.deb ... 237s Unpacking libavahi-common-data:s390x (0.8-13ubuntu6) ... 237s Selecting previously unselected package libavahi-common3:s390x. 237s Preparing to unpack .../10-libavahi-common3_0.8-13ubuntu6_s390x.deb ... 237s Unpacking libavahi-common3:s390x (0.8-13ubuntu6) ... 237s Selecting previously unselected package libavahi-client3:s390x. 237s Preparing to unpack .../11-libavahi-client3_0.8-13ubuntu6_s390x.deb ... 237s Unpacking libavahi-client3:s390x (0.8-13ubuntu6) ... 237s Selecting previously unselected package libwbclient0:s390x. 237s Preparing to unpack .../12-libwbclient0_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 237s Unpacking libwbclient0:s390x (2:4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package samba-libs:s390x. 237s Preparing to unpack .../13-samba-libs_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 237s Unpacking samba-libs:s390x (2:4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package python3-talloc:s390x. 237s Preparing to unpack .../14-python3-talloc_2.4.2-1build2_s390x.deb ... 237s Unpacking python3-talloc:s390x (2.4.2-1build2) ... 237s Selecting previously unselected package python3-samba. 237s Preparing to unpack .../15-python3-samba_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 237s Unpacking python3-samba (2:4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package samba-common. 237s Preparing to unpack .../16-samba-common_2%3a4.19.5+dfsg-4ubuntu9_all.deb ... 237s Unpacking samba-common (2:4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package libcups2t64:s390x. 237s Preparing to unpack .../17-libcups2t64_2.4.7-1.2ubuntu7_s390x.deb ... 237s Unpacking libcups2t64:s390x (2.4.7-1.2ubuntu7) ... 237s Selecting previously unselected package samba-common-bin. 237s Preparing to unpack .../18-samba-common-bin_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 237s Unpacking samba-common-bin (2:4.19.5+dfsg-4ubuntu9) ... 237s Selecting previously unselected package tdb-tools. 237s Preparing to unpack .../19-tdb-tools_1.4.10-1build1_s390x.deb ... 237s Unpacking tdb-tools (1.4.10-1build1) ... 237s Selecting previously unselected package samba. 237s Preparing to unpack .../20-samba_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 238s Unpacking samba (2:4.19.5+dfsg-4ubuntu9) ... 238s Selecting previously unselected package libnsl2:s390x. 238s Preparing to unpack .../21-libnsl2_1.3.0-3build3_s390x.deb ... 238s Unpacking libnsl2:s390x (1.3.0-3build3) ... 238s Selecting previously unselected package autofs. 238s Preparing to unpack .../22-autofs_5.1.9-1ubuntu4_s390x.deb ... 238s Unpacking autofs (5.1.9-1ubuntu4) ... 238s Selecting previously unselected package cifs-utils. 238s Preparing to unpack .../23-cifs-utils_2%3a7.0-2build1_s390x.deb ... 238s Unpacking cifs-utils (2:7.0-2build1) ... 238s Selecting previously unselected package libidn12:s390x. 238s Preparing to unpack .../24-libidn12_1.42-2_s390x.deb ... 238s Unpacking libidn12:s390x (1.42-2) ... 238s Selecting previously unselected package libsmbclient0:s390x. 238s Preparing to unpack .../25-libsmbclient0_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 238s Unpacking libsmbclient0:s390x (2:4.19.5+dfsg-4ubuntu9) ... 238s Selecting previously unselected package smbclient. 238s Preparing to unpack .../26-smbclient_2%3a4.19.5+dfsg-4ubuntu9_s390x.deb ... 238s Unpacking smbclient (2:4.19.5+dfsg-4ubuntu9) ... 238s Selecting previously unselected package libhesiod0:s390x. 238s Preparing to unpack .../27-libhesiod0_3.2.1-4build1_s390x.deb ... 238s Unpacking libhesiod0:s390x (3.2.1-4build1) ... 238s Selecting previously unselected package autofs-hesiod. 238s Preparing to unpack .../28-autofs-hesiod_5.1.9-1ubuntu4_s390x.deb ... 238s Unpacking autofs-hesiod (5.1.9-1ubuntu4) ... 238s Selecting previously unselected package autofs-ldap. 238s Preparing to unpack .../29-autofs-ldap_5.1.9-1ubuntu4_s390x.deb ... 238s Unpacking autofs-ldap (5.1.9-1ubuntu4) ... 238s Selecting previously unselected package autopkgtest-satdep. 238s Preparing to unpack .../30-1-autopkgtest-satdep.deb ... 238s Unpacking autopkgtest-satdep (0) ... 238s Setting up libtdb1:s390x (1.4.10-1build1) ... 238s Setting up samba-common (2:4.19.5+dfsg-4ubuntu9) ... 238s 238s Creating config file /etc/samba/smb.conf with new version 238s Setting up libwbclient0:s390x (2:4.19.5+dfsg-4ubuntu9) ... 238s Setting up libtalloc2:s390x (2.4.2-1build2) ... 238s Setting up libpython3.12t64:s390x (3.12.3-1) ... 238s Setting up python3-tdb (1.4.10-1build1) ... 238s Setting up libavahi-common-data:s390x (0.8-13ubuntu6) ... 238s Setting up tdb-tools (1.4.10-1build1) ... 238s update-alternatives: using /usr/bin/tdbbackup.tdbtools to provide /usr/bin/tdbbackup (tdbbackup) in auto mode 238s Setting up libidn12:s390x (1.42-2) ... 238s Setting up python3-markdown (3.6-1) ... 239s Setting up python3-dnspython (2.6.1-1ubuntu1) ... 239s Setting up libnsl2:s390x (1.3.0-3build3) ... 239s Setting up libtevent0t64:s390x (0.16.1-2build1) ... 239s Setting up autofs (5.1.9-1ubuntu4) ... 240s 240s Creating config file /etc/auto.master with new version 240s 240s Creating config file /etc/auto.net with new version 240s 240s Creating config file /etc/auto.misc with new version 240s 240s Creating config file /etc/auto.smb with new version 241s 241s Creating config file /etc/autofs.conf with new version 241s 241s Creating config file /etc/default/autofs with new version 241s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 241s Created symlink /etc/systemd/system/multi-user.target.wants/autofs.service → /usr/lib/systemd/system/autofs.service. 242s Setting up python3-talloc:s390x (2.4.2-1build2) ... 242s Setting up cifs-utils (2:7.0-2build1) ... 242s update-alternatives: using /usr/lib/s390x-linux-gnu/cifs-utils/idmapwb.so to provide /etc/cifs-utils/idmap-plugin (idmap-plugin) in auto mode 242s Setting up libavahi-common3:s390x (0.8-13ubuntu6) ... 242s Setting up libhesiod0:s390x (3.2.1-4build1) ... 242s Setting up autofs-ldap (5.1.9-1ubuntu4) ... 242s Setting up libldb2:s390x (2:2.8.0+samba4.19.5+dfsg-4ubuntu9) ... 242s Setting up libavahi-client3:s390x (0.8-13ubuntu6) ... 242s Setting up autofs-hesiod (5.1.9-1ubuntu4) ... 242s Setting up samba-libs:s390x (2:4.19.5+dfsg-4ubuntu9) ... 242s Setting up python3-ldb (2:2.8.0+samba4.19.5+dfsg-4ubuntu9) ... 242s Setting up libsmbclient0:s390x (2:4.19.5+dfsg-4ubuntu9) ... 242s Setting up libcups2t64:s390x (2.4.7-1.2ubuntu7) ... 242s Setting up python3-samba (2:4.19.5+dfsg-4ubuntu9) ... 243s Setting up smbclient (2:4.19.5+dfsg-4ubuntu9) ... 243s Setting up samba-common-bin (2:4.19.5+dfsg-4ubuntu9) ... 243s Setting up samba (2:4.19.5+dfsg-4ubuntu9) ... 245s Created symlink /etc/systemd/system/smb.service → /usr/lib/systemd/system/smbd.service. 245s Created symlink /etc/systemd/system/multi-user.target.wants/smbd.service → /usr/lib/systemd/system/smbd.service. 246s Created symlink /etc/systemd/system/nmb.service → /usr/lib/systemd/system/nmbd.service. 246s Created symlink /etc/systemd/system/multi-user.target.wants/nmbd.service → /usr/lib/systemd/system/nmbd.service. 247s Created symlink /etc/systemd/system/samba.service → /usr/lib/systemd/system/samba-ad-dc.service. 247s Created symlink /etc/systemd/system/multi-user.target.wants/samba-ad-dc.service → /usr/lib/systemd/system/samba-ad-dc.service. 248s Setting up autopkgtest-satdep (0) ... 248s Processing triggers for ufw (0.36.2-6) ... 248s Processing triggers for man-db (2.12.1-1) ... 249s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 253s (Reading database ... 55706 files and directories currently installed.) 253s Removing autopkgtest-satdep (0) ... 254s autopkgtest [13:18:13]: test smb-mount: [----------------------- 254s + date --utc 254s + now=Mon Jun 3 13:18:13 UTC 2024 254s + result=0 254s + systemctl restart autofs 254s + echo Setting up a public samba share in /pub 254s + mkdir -p /pub 254s Setting up a public samba share in /pub 254s + echo Mon Jun 3 13:18:13 UTC 2024 - This is the public samba share. 254s + add_smb_share pub /pub yes 254s + local share=pub 254s + local sharepath=/pub 254s + local public=yes 254s + + grep -qE ^\[pub\] 254s testparm -s 254s + echo Adding [pub] share 254s + cat 254s Adding [pub] share 254s + [ yes = yes ] 254s + cat 254s + systemctl reload smbd.service 254s Setting up a private samba share in /private 254s + echo Setting up a private samba share in /private 254s + mkdir -p /private 254s + echo Mon Jun 3 13:18:13 UTC 2024 - This is the private samba share. 254s + add_smb_share private /private no 254s + local share=private 254s + local sharepath=/private 254s + local public=no 254s + + grep -qE ^\[private\] 254s testparm -s 254s Adding [private] share 254s + echo Adding [private] share 254s + cat 254s + [ no = yes ] 254s + cat 254s + systemctl reload smbd.service 254s + username=smbtest3696 254s + password=3696 254s + echo Creating a local test user called smbtest3696 254s + create_user smbtest3696 3696 254s + localCreating a local test user called smbtest3696 254s username=smbtest3696 254s + local password=3696 254s + useradd -m smbtest3696 254s + Setting samba password for the smbtest3696 user 254s echo Setting samba password for the smbtest3696 user 254s + + smbpasswd -s -a smbtest3696 254s echo 3696 254s + echo 3696 254s Added user smbtest3696. 254s + echo Setting up autofs credentials for the private share 254s Setting up autofs credentials for the private share 254s + mkdir -m 0700 -p /etc/creds 254s + cat 254s + chmod 0600 /etc/creds/localhost 254s + grep -qE ^/cifs /etc/auto.master 254s + echo Configuring autofs for the /cifs mountpoint 254s + Configuring autofs for the /cifs mountpoint 254s echo /cifs /etc/auto.smb --timeout=180 254s + systemctl restart autofs 254s Testing authenticated share automount 254s + echo Testing authenticated share automount 254s + timeout 30s grep -qE Mon Jun 3 13:18:13 UTC 2024.*private /cifs/localhost/private/hello-private.txt 255s + Confirming with smbstatus that an authenticated connection was used 255s [ 0 -ne 0 ] 255s + echo Confirming with smbstatus that an authenticated connection was used 255s + smbstatus 255s + output= 255s Samba version 4.19.5-Ubuntu 255s PID Username Group Machine Protocol Version Encryption Signing 255s ---------------------------------------------------------------------------------------------------------------------------------------- 255s 3798 smbtest3696 smbtest3696 ::1 (ipv6:::1:60540) SMB3_11 - partial(AES-128-CMAC) 255s 255s Service pid Machine Connected at Encryption Signing 255s --------------------------------------------------------------------------------------------- 255s private 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s 255s 255s Locked files: 255s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 255s -------------------------------------------------------------------------------------------------- 255s 3798 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Mon Jun 3 13:18:14 2024 255s + + grep -q smbtest3696 255s echo 255s Samba version 4.19.5-Ubuntu 255s PID Username Group Machine Protocol Version Encryption Signing 255s ---------------------------------------------------------------------------------------------------------------------------------------- 255s 3798 smbtest3696 smbtest3696 ::1 (ipv6:::1:60540) SMB3_11 - partial(AES-128-CMAC) 255s 255s Service pid Machine Connected at Encryption Signing 255s --------------------------------------------------------------------------------------------- 255s private 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s 255s 255s Locked files: 255s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 255s -------------------------------------------------------------------------------------------------- 255s 3798 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Mon Jun 3 13:18:14 2024 255s Confirming with mount -t cifs that the filesystem is mounted with authentication 255s Testing unauthenticated share automount 255s + [ 0 -ne 0 ] 255s + echo Confirming with mount -t cifs that the filesystem is mounted with authentication 255s + mount -t cifs 255s + output=//localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest3696,domain=WORKGROUP,uid=0,noforceuid,gid=0,noforcegid,addr=0000:0000:0000:0000:0000:0000:0000:0001,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s + + echo //localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest3696,domain=WORKGROUP,uid=0,noforceuid,gid=0,noforcegid,addr=0000:0000:0000:0000:0000:0000:0000:0001,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s grep -qE on /cifs/localhost/private.*username=smbtest3696 255s + [ 0 -ne 0 ] 255s + echo Testing unauthenticated share automount 255s + timeout 30s grep -q Mon Jun 3 13:18:13 UTC 2024.*public /cifs/127.0.0.1/pub/hello-public.txt 255s + [ 0 -ne 0 ] 255s + echo Confirming with smbstatus that a guest connection was used 255s + smbstatus 255s Confirming with smbstatus that a guest connection was used 255s + output= 255s Samba version 4.19.5-Ubuntu 255s PID Username Group Machine Protocol Version Encryption Signing 255s ---------------------------------------------------------------------------------------------------------------------------------------- 255s 3824 nobody nogroup 127.0.0.1 (ipv4:127.0.0.1:34270) SMB3_11 - - 255s 3798 smbtest3696 smbtest3696 ::1 (ipv6:::1:60540) SMB3_11 - partial(AES-128-CMAC) 255s 255s Service pid Machine Connected at Encryption Signing 255s --------------------------------------------------------------------------------------------- 255s private 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s pub 3824 127.0.0.1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3824 127.0.0.1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s 255s 255s Locked files: 255s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 255s -------------------------------------------------------------------------------------------------- 255s 3824 65534 DENY_NONE 0x120089 RDONLY LEASE(RWH) /pub hello-public.txt Mon Jun 3 13:18:14 2024 255s 3798 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Mon Jun 3 13:18:14 2024 255s + + grepecho 255s Samba version 4.19.5-Ubuntu 255s PID Username Group Machine Protocol Version Encryption Signing 255s ---------------------------------------------------------------------------------------------------------------------------------------- 255s 3824 nobody nogroup 127.0.0.1 (ipv4:127.0.0.1:34270) SMB3_11 - - 255s 3798 smbtest3696 smbtest3696 ::1 (ipv6:::1:60540) SMB3_11 - partial(AES-128-CMAC) 255s 255s Service pid Machine Connected at Encryption Signing 255s --------------------------------------------------------------------------------------------- 255s private 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s pub 3824 127.0.0.1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3824 127.0.0.1 Mon Jun 3 13:18:15 2024 UTC - - 255s IPC$ 3798 ::1 Mon Jun 3 13:18:15 2024 UTC - - 255s 255s 255s Locked files: 255s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 255s -------------------------------------------------------------------------------------------------- 255s 3824 65534 DENY_NONE 0x120089 RDONLY LEASE(RWH) /pub hello-public.txt Mon Jun 3 13:18:14 2024 255s 3798 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Mon Jun 3 13:18:14 2024 255s -q nobody 255s + [ 0 -ne 0 ] 255s + echo Confirming with mount -t cifs that the filesystem is mounted without authentication 255s + mount -t cifsConfirming with mount -t cifs that the filesystem is mounted without authentication 255s 255s + output=//localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest3696,domain=WORKGROUP,uid=0,noforceuid,gid=0,noforcegid,addr=0000:0000:0000:0000:0000:0000:0000:0001,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s //127.0.0.1/pub on /cifs/127.0.0.1/pub type cifs (rw,relatime,vers=3.1.1,sec=none,cache=strict,uid=0,noforceuid,gid=0,noforcegid,addr=127.0.0.1,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s + + echogrep //localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest3696,domain=WORKGROUP,uid=0,noforceuid,gid=0,noforcegid,addr=0000:0000:0000:0000:0000:0000:0000:0001,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s //127.0.0.1/pub on /cifs/127.0.0.1/pub type cifs (rw,relatime,vers=3.1.1,sec=none,cache=strict,uid=0,noforceuid,gid=0,noforcegid,addr=127.0.0.1,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,retrans=1,echo_interval=60,actimeo=1,closetimeo=1) 255s -qE on /cifs/127.0.0.1/pub.*sec=none 255s + [ 0 -ne 0 ] 256s autopkgtest [13:18:15]: test smb-mount: -----------------------] 259s autopkgtest [13:18:18]: test smb-mount: - - - - - - - - - - results - - - - - - - - - - 259s smb-mount PASS 260s autopkgtest [13:18:19]: test nfs-mount: preparing testbed 471s autopkgtest [13:21:50]: testbed dpkg architecture: s390x 472s autopkgtest [13:21:51]: testbed apt version: 2.9.3 472s autopkgtest [13:21:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 473s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 474s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 474s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 474s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 474s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 474s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [62.1 kB] 474s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [305 kB] 474s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [1392 B] 474s Fetched 913 kB in 1s (1039 kB/s) 474s Reading package lists... 478s Reading package lists... 478s Building dependency tree... 478s Reading state information... 479s Calculating upgrade... 479s The following packages will be upgraded: 479s ethtool libsensors-config libsensors5 libxml2 480s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 480s Need to get 925 kB of archives. 480s After this operation, 442 kB disk space will be freed. 480s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libxml2 s390x 2.12.7+dfsg-3 [663 kB] 480s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x ethtool s390x 1:6.9-1 [229 kB] 480s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x libsensors-config all 1:3.6.0-10 [5532 B] 480s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libsensors5 s390x 1:3.6.0-10 [26.9 kB] 481s Fetched 925 kB in 1s (1625 kB/s) 481s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 54601 files and directories currently installed.) 481s Preparing to unpack .../libxml2_2.12.7+dfsg-3_s390x.deb ... 481s Unpacking libxml2:s390x (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 481s Preparing to unpack .../ethtool_1%3a6.9-1_s390x.deb ... 481s Unpacking ethtool (1:6.9-1) over (1:6.7-1build1) ... 481s Preparing to unpack .../libsensors-config_1%3a3.6.0-10_all.deb ... 481s Unpacking libsensors-config (1:3.6.0-10) over (1:3.6.0-9build1) ... 481s Preparing to unpack .../libsensors5_1%3a3.6.0-10_s390x.deb ... 481s Unpacking libsensors5:s390x (1:3.6.0-10) over (1:3.6.0-9build1) ... 481s Setting up libsensors-config (1:3.6.0-10) ... 481s Setting up libsensors5:s390x (1:3.6.0-10) ... 481s Setting up libxml2:s390x (2.12.7+dfsg-3) ... 481s Setting up ethtool (1:6.9-1) ... 481s Processing triggers for man-db (2.12.1-1) ... 482s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 483s Reading package lists... 483s Building dependency tree... 483s Reading state information... 483s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 484s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 484s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 484s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 484s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 486s Reading package lists... 486s Reading package lists... 486s Building dependency tree... 486s Reading state information... 486s Calculating upgrade... 487s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 487s Reading package lists... 487s Building dependency tree... 487s Reading state information... 488s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 495s Reading package lists... 495s Building dependency tree... 495s Reading state information... 495s Starting pkgProblemResolver with broken count: 0 495s Starting 2 pkgProblemResolver with broken count: 0 495s Done 496s The following additional packages will be installed: 496s autofs autofs-hesiod autofs-ldap keyutils libhesiod0 libidn12 libnfsidmap1 496s libnsl2 nfs-common nfs-kernel-server rpcbind 496s Suggested packages: 496s open-iscsi watchdog 496s The following NEW packages will be installed: 496s autofs autofs-hesiod autofs-ldap autopkgtest-satdep keyutils libhesiod0 496s libidn12 libnfsidmap1 libnsl2 nfs-common nfs-kernel-server rpcbind 496s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 496s Need to get 1056 kB/1057 kB of archives. 496s After this operation, 3581 kB of additional disk space will be used. 496s Get:1 /tmp/autopkgtest.ioZNtw/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 496s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x libnfsidmap1 s390x 1:2.6.4-3ubuntu5 [50.1 kB] 497s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x rpcbind s390x 1.2.6-7ubuntu2 [47.7 kB] 497s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x keyutils s390x 1.6.3-3build1 [57.7 kB] 497s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x nfs-common s390x 1:2.6.4-3ubuntu5 [253 kB] 497s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x libnsl2 s390x 1.3.0-3build3 [44.1 kB] 497s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x autofs s390x 5.1.9-1ubuntu4 [294 kB] 497s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x libidn12 s390x 1.42-2 [58.5 kB] 497s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x nfs-kernel-server s390x 1:2.6.4-3ubuntu5 [174 kB] 497s Get:10 http://ftpmaster.internal/ubuntu oracular/universe s390x libhesiod0 s390x 3.2.1-4build1 [23.5 kB] 497s Get:11 http://ftpmaster.internal/ubuntu oracular/universe s390x autofs-hesiod s390x 5.1.9-1ubuntu4 [10.6 kB] 497s Get:12 http://ftpmaster.internal/ubuntu oracular/universe s390x autofs-ldap s390x 5.1.9-1ubuntu4 [44.4 kB] 498s Preconfiguring packages ... 498s Fetched 1056 kB in 1s (1060 kB/s) 498s Selecting previously unselected package libnfsidmap1:s390x. 498s (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 ... 54601 files and directories currently installed.) 498s Preparing to unpack .../00-libnfsidmap1_1%3a2.6.4-3ubuntu5_s390x.deb ... 498s Unpacking libnfsidmap1:s390x (1:2.6.4-3ubuntu5) ... 498s Selecting previously unselected package rpcbind. 498s Preparing to unpack .../01-rpcbind_1.2.6-7ubuntu2_s390x.deb ... 498s Unpacking rpcbind (1.2.6-7ubuntu2) ... 498s Selecting previously unselected package keyutils. 498s Preparing to unpack .../02-keyutils_1.6.3-3build1_s390x.deb ... 498s Unpacking keyutils (1.6.3-3build1) ... 498s Selecting previously unselected package nfs-common. 498s Preparing to unpack .../03-nfs-common_1%3a2.6.4-3ubuntu5_s390x.deb ... 498s Unpacking nfs-common (1:2.6.4-3ubuntu5) ... 498s Selecting previously unselected package libnsl2:s390x. 498s Preparing to unpack .../04-libnsl2_1.3.0-3build3_s390x.deb ... 498s Unpacking libnsl2:s390x (1.3.0-3build3) ... 498s Selecting previously unselected package autofs. 498s Preparing to unpack .../05-autofs_5.1.9-1ubuntu4_s390x.deb ... 498s Unpacking autofs (5.1.9-1ubuntu4) ... 498s Selecting previously unselected package libidn12:s390x. 498s Preparing to unpack .../06-libidn12_1.42-2_s390x.deb ... 498s Unpacking libidn12:s390x (1.42-2) ... 498s Selecting previously unselected package nfs-kernel-server. 498s Preparing to unpack .../07-nfs-kernel-server_1%3a2.6.4-3ubuntu5_s390x.deb ... 498s Unpacking nfs-kernel-server (1:2.6.4-3ubuntu5) ... 498s Selecting previously unselected package libhesiod0:s390x. 498s Preparing to unpack .../08-libhesiod0_3.2.1-4build1_s390x.deb ... 498s Unpacking libhesiod0:s390x (3.2.1-4build1) ... 498s Selecting previously unselected package autofs-hesiod. 498s Preparing to unpack .../09-autofs-hesiod_5.1.9-1ubuntu4_s390x.deb ... 498s Unpacking autofs-hesiod (5.1.9-1ubuntu4) ... 498s Selecting previously unselected package autofs-ldap. 498s Preparing to unpack .../10-autofs-ldap_5.1.9-1ubuntu4_s390x.deb ... 498s Unpacking autofs-ldap (5.1.9-1ubuntu4) ... 498s Selecting previously unselected package autopkgtest-satdep. 498s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 498s Unpacking autopkgtest-satdep (0) ... 499s Setting up libnfsidmap1:s390x (1:2.6.4-3ubuntu5) ... 499s Setting up rpcbind (1.2.6-7ubuntu2) ... 499s Created symlink /etc/systemd/system/multi-user.target.wants/rpcbind.service → /usr/lib/systemd/system/rpcbind.service. 499s Created symlink /etc/systemd/system/sockets.target.wants/rpcbind.socket → /usr/lib/systemd/system/rpcbind.socket. 500s Setting up libidn12:s390x (1.42-2) ... 500s Setting up keyutils (1.6.3-3build1) ... 500s Setting up libnsl2:s390x (1.3.0-3build3) ... 500s Setting up autofs (5.1.9-1ubuntu4) ... 500s 500s Creating config file /etc/auto.master with new version 501s 501s Creating config file /etc/auto.net with new version 501s 501s Creating config file /etc/auto.misc with new version 502s 502s Creating config file /etc/auto.smb with new version 502s 502s Creating config file /etc/autofs.conf with new version 502s 502s Creating config file /etc/default/autofs with new version 503s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 503s Created symlink /etc/systemd/system/multi-user.target.wants/autofs.service → /usr/lib/systemd/system/autofs.service. 504s Setting up libhesiod0:s390x (3.2.1-4build1) ... 504s Setting up nfs-common (1:2.6.4-3ubuntu5) ... 504s 504s Creating config file /etc/idmapd.conf with new version 505s 505s Creating config file /etc/nfs.conf with new version 505s info: Selecting UID from range 100 to 999 ... 505s 505s info: Adding system user `statd' (UID 108) ... 505s info: Adding new user `statd' (UID 108) with group `nogroup' ... 505s info: Not creating home directory `/var/lib/nfs'. 506s Created symlink /etc/systemd/system/multi-user.target.wants/nfs-client.target → /usr/lib/systemd/system/nfs-client.target. 506s Created symlink /etc/systemd/system/remote-fs.target.wants/nfs-client.target → /usr/lib/systemd/system/nfs-client.target. 507s auth-rpcgss-module.service is a disabled or a static unit, not starting it. 507s nfs-idmapd.service is a disabled or a static unit, not starting it. 507s nfs-utils.service is a disabled or a static unit, not starting it. 507s proc-fs-nfsd.mount is a disabled or a static unit, not starting it. 507s rpc-gssd.service is a disabled or a static unit, not starting it. 507s rpc-statd-notify.service is a disabled or a static unit, not starting it. 507s rpc-statd.service is a disabled or a static unit, not starting it. 507s rpc-svcgssd.service is a disabled or a static unit, not starting it. 507s Setting up autofs-ldap (5.1.9-1ubuntu4) ... 507s Setting up nfs-kernel-server (1:2.6.4-3ubuntu5) ... 507s Created symlink /etc/systemd/system/nfs-mountd.service.requires/fsidd.service → /usr/lib/systemd/system/fsidd.service. 507s Created symlink /etc/systemd/system/nfs-server.service.requires/fsidd.service → /usr/lib/systemd/system/fsidd.service. 508s Created symlink /etc/systemd/system/nfs-client.target.wants/nfs-blkmap.service → /usr/lib/systemd/system/nfs-blkmap.service. 508s Created symlink /etc/systemd/system/multi-user.target.wants/nfs-server.service → /usr/lib/systemd/system/nfs-server.service. 508s nfs-mountd.service is a disabled or a static unit, not starting it. 508s nfsdcld.service is a disabled or a static unit, not starting it. 509s 509s Creating config file /etc/exports with new version 509s 509s Creating config file /etc/default/nfs-kernel-server with new version 510s Setting up autofs-hesiod (5.1.9-1ubuntu4) ... 510s Setting up autopkgtest-satdep (0) ... 510s Processing triggers for man-db (2.12.1-1) ... 511s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 516s (Reading database ... 54880 files and directories currently installed.) 517s Removing autopkgtest-satdep (0) ... 526s autopkgtest [13:22:45]: test nfs-mount: [----------------------- 526s + set -e 526s + date --utc 526s + now=Mon Jun 3 13:22:45 UTC 2024 526s + result=0 526s + echo creating /nfspub nfs export 526s + mkdir -p /nfspub 527s + echo Mon Jun 3 13:22:45 UTC 2024 - This is an nfs public export 527s + echo /nfspub *(ro,no_subtree_check) 527s + exportfs -avr 527s + echo Configuring automount for nfs on /net 527s + mkdir -p /etc/auto.master.d 527s + echo /net -hosts -intr,soft --timeout=180 527s + systemctl restart autofs 527s creating /nfspub nfs export 527s exporting *:/nfspub 527s Configuring automount for nfs on /net 527s + echo Accessing the autofs mountpoint 527s + timeout 30s grep -qE Mon Jun 3 13:22:45 UTC 2024.*nfs public /net/localhost/nfspub/nfs-public.txt 527s Accessing the autofs mountpoint 527s + [ 0 -ne 0 ] 527s autopkgtest [13:22:46]: test nfs-mount: -----------------------] 528s autopkgtest [13:22:47]: test nfs-mount: - - - - - - - - - - results - - - - - - - - - - 528s nfs-mount PASS 528s autopkgtest [13:22:47]: test ldap-map-sasl-auth: preparing testbed 531s Reading package lists... 531s Building dependency tree... 531s Reading state information... 532s Starting pkgProblemResolver with broken count: 0 532s Starting 2 pkgProblemResolver with broken count: 0 532s Done 533s The following additional packages will be installed: 533s db-util db5.3-util krb5-admin-server krb5-config krb5-kdc krb5-user 533s ldap-utils libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 533s libkadm5srv-mit12 libkdb5-10t64 libltdl7 libodbc2 533s libsasl2-modules-gssapi-mit libverto-libevent1t64 libverto1t64 sasl2-bin 533s schema2ldif slapd 533s Suggested packages: 533s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc odbc-postgresql tdsodbc 533s The following NEW packages will be installed: 533s autopkgtest-satdep db-util db5.3-util krb5-admin-server krb5-config krb5-kdc 533s krb5-user ldap-utils libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 533s libkadm5srv-mit12 libkdb5-10t64 libltdl7 libodbc2 533s libsasl2-modules-gssapi-mit libverto-libevent1t64 libverto1t64 sasl2-bin 533s schema2ldif slapd 533s 0 upgraded, 21 newly installed, 0 to remove and 0 not upgraded. 533s Need to get 2993 kB/2994 kB of archives. 533s After this operation, 9908 kB of additional disk space will be used. 533s Get:1 /tmp/autopkgtest.ioZNtw/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [800 B] 533s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x krb5-config all 2.7 [22.0 kB] 533s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libgssrpc4t64 s390x 1.20.1-6ubuntu2 [60.5 kB] 533s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libkadm5clnt-mit12 s390x 1.20.1-6ubuntu2 [40.9 kB] 533s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libkdb5-10t64 s390x 1.20.1-6ubuntu2 [42.4 kB] 533s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libkadm5srv-mit12 s390x 1.20.1-6ubuntu2 [55.9 kB] 533s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x krb5-user s390x 1.20.1-6ubuntu2 [110 kB] 533s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 533s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 533s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 533s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x krb5-kdc s390x 1.20.1-6ubuntu2 [191 kB] 534s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x krb5-admin-server s390x 1.20.1-6ubuntu2 [96.9 kB] 534s Get:13 http://ftpmaster.internal/ubuntu oracular/main s390x db5.3-util s390x 5.3.28+dfsg2-7 [75.6 kB] 534s Get:14 http://ftpmaster.internal/ubuntu oracular/main s390x db-util all 1:5.3.21ubuntu2 [2412 B] 534s Get:15 http://ftpmaster.internal/ubuntu oracular/main s390x sasl2-bin s390x 2.1.28+dfsg1-5ubuntu3 [97.9 kB] 534s Get:16 http://ftpmaster.internal/ubuntu oracular/main s390x libltdl7 s390x 2.4.7-7build1 [41.8 kB] 534s Get:17 http://ftpmaster.internal/ubuntu oracular/main s390x libodbc2 s390x 2.3.12-1build2 [164 kB] 534s Get:18 http://ftpmaster.internal/ubuntu oracular/main s390x slapd s390x 2.6.7+dfsg-1~exp1ubuntu8 [1617 kB] 535s Get:19 http://ftpmaster.internal/ubuntu oracular/main s390x ldap-utils s390x 2.6.7+dfsg-1~exp1ubuntu8 [165 kB] 535s Get:20 http://ftpmaster.internal/ubuntu oracular/main s390x libsasl2-modules-gssapi-mit s390x 2.1.28+dfsg1-5ubuntu3 [31.8 kB] 535s Get:21 http://ftpmaster.internal/ubuntu oracular/universe s390x schema2ldif all 1.3-4 [15.1 kB] 536s Preconfiguring packages ... 536s Fetched 2993 kB in 2s (1301 kB/s) 536s Selecting previously unselected package krb5-config. 536s (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 ... 54880 files and directories currently installed.) 536s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 536s Unpacking krb5-config (2.7) ... 536s Selecting previously unselected package libgssrpc4t64:s390x. 536s Preparing to unpack .../01-libgssrpc4t64_1.20.1-6ubuntu2_s390x.deb ... 536s Unpacking libgssrpc4t64:s390x (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package libkadm5clnt-mit12:s390x. 537s Preparing to unpack .../02-libkadm5clnt-mit12_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking libkadm5clnt-mit12:s390x (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package libkdb5-10t64:s390x. 537s Preparing to unpack .../03-libkdb5-10t64_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking libkdb5-10t64:s390x (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package libkadm5srv-mit12:s390x. 537s Preparing to unpack .../04-libkadm5srv-mit12_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking libkadm5srv-mit12:s390x (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package krb5-user. 537s Preparing to unpack .../05-krb5-user_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking krb5-user (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package libevent-2.1-7t64:s390x. 537s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 537s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 537s Selecting previously unselected package libverto1t64:s390x. 537s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 537s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 537s Selecting previously unselected package libverto-libevent1t64:s390x. 537s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 537s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 537s Selecting previously unselected package krb5-kdc. 537s Preparing to unpack .../09-krb5-kdc_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking krb5-kdc (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package krb5-admin-server. 537s Preparing to unpack .../10-krb5-admin-server_1.20.1-6ubuntu2_s390x.deb ... 537s Unpacking krb5-admin-server (1.20.1-6ubuntu2) ... 537s Selecting previously unselected package db5.3-util. 537s Preparing to unpack .../11-db5.3-util_5.3.28+dfsg2-7_s390x.deb ... 537s Unpacking db5.3-util (5.3.28+dfsg2-7) ... 537s Selecting previously unselected package db-util. 537s Preparing to unpack .../12-db-util_1%3a5.3.21ubuntu2_all.deb ... 537s Unpacking db-util (1:5.3.21ubuntu2) ... 537s Selecting previously unselected package sasl2-bin. 537s Preparing to unpack .../13-sasl2-bin_2.1.28+dfsg1-5ubuntu3_s390x.deb ... 537s Unpacking sasl2-bin (2.1.28+dfsg1-5ubuntu3) ... 537s Selecting previously unselected package libltdl7:s390x. 537s Preparing to unpack .../14-libltdl7_2.4.7-7build1_s390x.deb ... 537s Unpacking libltdl7:s390x (2.4.7-7build1) ... 537s Selecting previously unselected package libodbc2:s390x. 537s Preparing to unpack .../15-libodbc2_2.3.12-1build2_s390x.deb ... 537s Unpacking libodbc2:s390x (2.3.12-1build2) ... 537s Selecting previously unselected package slapd. 537s Preparing to unpack .../16-slapd_2.6.7+dfsg-1~exp1ubuntu8_s390x.deb ... 537s Unpacking slapd (2.6.7+dfsg-1~exp1ubuntu8) ... 537s Selecting previously unselected package ldap-utils. 537s Preparing to unpack .../17-ldap-utils_2.6.7+dfsg-1~exp1ubuntu8_s390x.deb ... 537s Unpacking ldap-utils (2.6.7+dfsg-1~exp1ubuntu8) ... 537s Selecting previously unselected package libsasl2-modules-gssapi-mit:s390x. 537s Preparing to unpack .../18-libsasl2-modules-gssapi-mit_2.1.28+dfsg1-5ubuntu3_s390x.deb ... 537s Unpacking libsasl2-modules-gssapi-mit:s390x (2.1.28+dfsg1-5ubuntu3) ... 537s Selecting previously unselected package schema2ldif. 537s Preparing to unpack .../19-schema2ldif_1.3-4_all.deb ... 537s Unpacking schema2ldif (1.3-4) ... 537s Selecting previously unselected package autopkgtest-satdep. 537s Preparing to unpack .../20-3-autopkgtest-satdep.deb ... 537s Unpacking autopkgtest-satdep (0) ... 538s Setting up schema2ldif (1.3-4) ... 538s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 538s Setting up ldap-utils (2.6.7+dfsg-1~exp1ubuntu8) ... 538s Setting up libgssrpc4t64:s390x (1.20.1-6ubuntu2) ... 538s Setting up krb5-config (2.7) ... 538s Setting up db5.3-util (5.3.28+dfsg2-7) ... 538s Setting up libltdl7:s390x (2.4.7-7build1) ... 538s Setting up libodbc2:s390x (2.3.12-1build2) ... 538s Setting up libsasl2-modules-gssapi-mit:s390x (2.1.28+dfsg1-5ubuntu3) ... 538s Setting up libkadm5clnt-mit12:s390x (1.20.1-6ubuntu2) ... 538s Setting up slapd (2.6.7+dfsg-1~exp1ubuntu8) ... 538s Creating new user openldap... done. 538s Creating initial configuration... done. 538s Creating LDAP directory... done. 539s Setting up db-util (1:5.3.21ubuntu2) ... 539s Setting up libkdb5-10t64:s390x (1.20.1-6ubuntu2) ... 539s Setting up sasl2-bin (2.1.28+dfsg1-5ubuntu3) ... 541s saslauthd.service is a disabled or a static unit, not starting it. 541s Setting up libkadm5srv-mit12:s390x (1.20.1-6ubuntu2) ... 541s Setting up krb5-user (1.20.1-6ubuntu2) ... 541s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 541s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 541s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 541s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 541s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 541s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 541s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 541s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 541s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 541s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 541s Setting up krb5-kdc (1.20.1-6ubuntu2) ... 542s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service. 543s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 543s Setting up krb5-admin-server (1.20.1-6ubuntu2) ... 544s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service. 544s Setting up autopkgtest-satdep (0) ... 544s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 544s Processing triggers for ufw (0.36.2-6) ... 545s Processing triggers for man-db (2.12.1-1) ... 551s (Reading database ... 55402 files and directories currently installed.) 551s Removing autopkgtest-satdep (0) ... 552s autopkgtest [13:23:11]: test ldap-map-sasl-auth: [----------------------- 553s ## Setting up Kerberos 553s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 553s master key name 'K/M@EXAMPLE.FAKE' 553s No policy specified for user4766@EXAMPLE.FAKE; defaulting to no policy 553s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 553s Principal "user4766@EXAMPLE.FAKE" created. 553s No policy specified for ldap/server.example.fake@EXAMPLE.FAKE; defaulting to no policy 553s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 553s Principal "ldap/server.example.fake@EXAMPLE.FAKE" created. 553s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 553s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 553s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 553s 553s ## Setting up slapd 554s Backing up /etc/ldap/slapd.d in /var/backups/slapd-2.6.7+dfsg-1~exp1ubuntu8... done. 554s Moving old database directory to /var/backups: 554s - directory unknown... done. 554s Creating initial configuration... done. 554s Creating LDAP directory... done. 555s Waiting for slapd to be ready . 555s 555s ## Configuring slapd 555s SASL/EXTERNAL authentication started 555s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 555s SASL SSF: 0 555s modifying entry "cn=config" 555s 555s modifying entry "olcDatabase={1}mdb,cn=config" 555s 555s 555s ## Adding autofs schema to ldap 555s 555s SASL/EXTERNAL authentication started 555s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 555s SASL SSF: 0 555s executing 'ldapadd -Y EXTERNAL -H ldapi:/// -f /etc/ldap/schema/autofs.ldif' 555s SASL/EXTERNAL authentication started 555s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 555s SASL SSF: 0 555s adding new entry "cn=autofs,cn=schema,cn=config" 555s 555s 555s ## Adding automount maps to ldap 555s adding new entry "ou=auto.indirect,dc=example,dc=fake" 555s 555s adding new entry "cn=/,ou=auto.indirect,dc=example,dc=fake" 555s 555s 555s ## Populating NFS export 555s exporting *:/storage 555s 555s ## Creating test user user4766 in sasldb 555s 555s ## Testing shared secret mechanism auth one by one before letting autofs try it 555s 555s Testing mechanism DIGEST-MD5 555s SASL/DIGEST-MD5 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 128 555s SASL data security layer installed. 555s dn:uid=user4766@example.fake,cn=digest-md5,cn=auth 555s 555s Testing mechanism SCRAM-SHA-1 555s SASL/SCRAM-SHA-1 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=scram-sha-1,cn=auth 555s 555s Testing mechanism SCRAM-SHA-224 555s SASL/SCRAM-SHA-224 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=scram-sha-224,cn=auth 555s 555s Testing mechanism SCRAM-SHA-256 555s SASL/SCRAM-SHA-256 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=scram-sha-256,cn=auth 555s 555s Testing mechanism SCRAM-SHA-384 555s SASL/SCRAM-SHA-384 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=scram-sha-384,cn=auth 555s 555s Testing mechanism SCRAM-SHA-512 555s SASL/SCRAM-SHA-512 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=scram-sha-512,cn=auth 555s 555s Testing mechanism NTLM 555s SASL/NTLM authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=ntlm,cn=auth 555s 555s Testing mechanism CRAM-MD5 555s SASL/CRAM-MD5 authentication started 555s SASL username: user4766@example.fake 555s SASL SSF: 0 555s dn:uid=user4766@example.fake,cn=cram-md5,cn=auth 555s 555s ## Testing GSSAPI mechanisms before letting autofs try it 555s 555s Password for user4766@EXAMPLE.FAKE: 555s Testing mechanism GSSAPI 555s SASL/GSSAPI authentication started 555s SASL username: user4766@EXAMPLE.FAKE 555s SASL SSF: 256 555s SASL data security layer installed. 555s dn:uid=user4766,cn=gssapi,cn=auth 555s 555s Testing mechanism GSS-SPNEGO 555s SASL/GSS-SPNEGO authentication started 555s SASL username: user4766@EXAMPLE.FAKE 555s SASL SSF: 256 555s SASL data security layer installed. 555s dn:uid=user4766,cn=gss-spnego,cn=auth 555s 555s ## Adding automount to nsswitch.conf 555s 555s ## Setting up autofs 555s 555s ## Testing autofs with SASL shared secret mechanisms 555s 555s ## Configuring autofs to use mechanism DIGEST-MD5 556s 556s ## Confirming target is not mounted 556s total 4 556s drwxr-xr-x 2 root root 0 Jun 3 13:23 . 556s drwxr-xr-x 23 root root 4096 Jun 3 13:23 .. 556s 556s ## Triggering a mount, and checking that the mountpoint has the test file 557s ls: cannot access '/mnt/storage/test_file_4766': No such file or directory 557s ## Something failed, gathering logs 557s 557s ## syslog: 557s 2024-06-03T13:22:29.318132+00:00 autopkgtest systemd[1]: Reloading finished in 621 ms. 557s 2024-06-03T13:22:29.418581+00:00 autopkgtest systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). 557s 2024-06-03T13:22:29.420922+00:00 autopkgtest systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). 557s 2024-06-03T13:22:29.421711+00:00 autopkgtest systemd[1]: rpc-svcgssd.service - RPC security service for NFS server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). 557s 2024-06-03T13:22:45.954603+00:00 autopkgtest systemd[1]: Stopping autofs.service - Automounts filesystems on demand... 557s 2024-06-03T13:22:45.956175+00:00 autopkgtest systemd[1]: autofs.service: Deactivated successfully. 557s 2024-06-03T13:22:45.956268+00:00 autopkgtest systemd[1]: Stopped autofs.service - Automounts filesystems on demand. 557s 2024-06-03T13:22:45.994489+00:00 autopkgtest (utomount)[3687]: autofs.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS 557s 2024-06-03T13:22:45.994671+00:00 autopkgtest systemd[1]: Starting autofs.service - Automounts filesystems on demand... 557s 2024-06-03T13:22:46.007257+00:00 autopkgtest systemd[1]: tmp-autos7tnpS.mount: Deactivated successfully. 557s 2024-06-03T13:22:46.021204+00:00 autopkgtest systemd[1]: Started autofs.service - Automounts filesystems on demand. 557s 2024-06-03T13:22:46.995631+00:00 autopkgtest systemd[1]: tmp-autojbdlr6.mount: Deactivated successfully. 557s 2024-06-03T13:22:57.689362+00:00 autopkgtest addgroup[4134]: Selecting GID from range 100 to 999 ... 557s 2024-06-03T13:22:57.692514+00:00 autopkgtest addgroup[4134]: Adding group `openldap' (GID 110) ... 557s 2024-06-03T13:22:57.799942+00:00 autopkgtest adduser[4144]: Selecting UID from range 100 to 999 ... 557s 2024-06-03T13:22:57.803162+00:00 autopkgtest adduser[4144]: Adding system user `openldap' (UID 109) ... 557s 2024-06-03T13:22:57.804851+00:00 autopkgtest adduser[4144]: Adding new user `openldap' (UID 109) with group `openldap' ... 557s 2024-06-03T13:22:57.821334+00:00 autopkgtest adduser[4144]: Creating home directory `/var/lib/ldap' ... 557s 2024-06-03T13:22:57.945640+00:00 autopkgtest kernel: audit: type=1400 audit(1717420977.920:122): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/slapd" pid=4205 comm="apparmor_parser" 557s 2024-06-03T13:22:57.951117+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4213 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:22:57.951334+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:22:58.394657+00:00 autopkgtest systemd[1]: Reloading finished in 442 ms. 557s 2024-06-03T13:22:58.524484+00:00 autopkgtest systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 557s 2024-06-03T13:22:58.546058+00:00 autopkgtest slapd[4264]: * Starting OpenLDAP slapd 557s 2024-06-03T13:22:58.558593+00:00 autopkgtest slapd[4270]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu8 (Apr 3 2024 18:47:41) $#012#011Ubuntu Developers 557s 2024-06-03T13:22:58.570616+00:00 autopkgtest slapd[4271]: slapd starting 557s 2024-06-03T13:22:58.575701+00:00 autopkgtest slapd[4264]: ...done. 557s 2024-06-03T13:22:58.576345+00:00 autopkgtest systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 557s 2024-06-03T13:22:58.849337+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4295 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:22:58.849463+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:22:59.243100+00:00 autopkgtest systemd[1]: Reloading finished in 399 ms. 557s 2024-06-03T13:22:59.508571+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4344 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:22:59.508798+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:22:59.938743+00:00 autopkgtest systemd[1]: Reloading finished in 427 ms. 557s 2024-06-03T13:23:00.389512+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4406 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:00.389638+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:00.870607+00:00 autopkgtest systemd[1]: Reloading finished in 482 ms. 557s 2024-06-03T13:23:01.237757+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4457 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:01.239457+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:01.858548+00:00 autopkgtest systemd[1]: Reloading finished in 617 ms. 557s 2024-06-03T13:23:02.040591+00:00 autopkgtest systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 557s 2024-06-03T13:23:02.058561+00:00 autopkgtest (krb5kdc)[4504]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 557s 2024-06-03T13:23:02.070879+00:00 autopkgtest krb5kdc[4504]: krb5kdc: cannot initialize realm LOCAL - see log file for details 557s 2024-06-03T13:23:02.081711+00:00 autopkgtest systemd[1]: krb5-kdc.service: Control process exited, code=exited, status=1/FAILURE 557s 2024-06-03T13:23:02.081865+00:00 autopkgtest systemd[1]: krb5-kdc.service: Failed with result 'exit-code'. 557s 2024-06-03T13:23:02.081923+00:00 autopkgtest systemd[1]: Failed to start krb5-kdc.service - Kerberos 5 Key Distribution Center. 557s 2024-06-03T13:23:02.298515+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4511 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:02.298950+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:02.745816+00:00 autopkgtest systemd[1]: Reloading finished in 445 ms. 557s 2024-06-03T13:23:03.110807+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4562 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:03.111547+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:03.657783+00:00 autopkgtest systemd[1]: Reloading finished in 545 ms. 557s 2024-06-03T13:23:03.797651+00:00 autopkgtest systemd[1]: Started krb5-admin-server.service - Kerberos 5 Admin Server. 557s 2024-06-03T13:23:03.899831+00:00 autopkgtest (kadmind)[4609]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 557s 2024-06-03T13:23:03.913114+00:00 autopkgtest kadmind[4609]: kadmind: Cannot open DB2 database '/var/lib/krb5kdc/principal': No such file or directory while initializing, aborting 557s 2024-06-03T13:23:03.925226+00:00 autopkgtest systemd[1]: krb5-admin-server.service: Main process exited, code=exited, status=1/FAILURE 557s 2024-06-03T13:23:03.925617+00:00 autopkgtest systemd[1]: krb5-admin-server.service: Failed with result 'exit-code'. 557s 2024-06-03T13:23:11.950188+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4769 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:11.955025+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:12.250009+00:00 autopkgtest systemd[1]: Reloading finished in 299 ms. 557s 2024-06-03T13:23:12.297193+00:00 autopkgtest systemd-resolved[371]: System hostname changed to 'server.example.fake'. 557s 2024-06-03T13:23:12.386167+00:00 autopkgtest systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 557s 2024-06-03T13:23:12.419413+00:00 autopkgtest systemd[1]: Started krb5-admin-server.service - Kerberos 5 Admin Server. 557s 2024-06-03T13:23:12.443112+00:00 autopkgtest (krb5kdc)[4820]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 557s 2024-06-03T13:23:12.462756+00:00 autopkgtest systemd[1]: krb5-kdc.service: Can't open PID file /run/krb5-kdc.pid (yet?) after start: No such file or directory 557s 2024-06-03T13:23:12.475803+00:00 autopkgtest systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center. 557s 2024-06-03T13:23:12.498815+00:00 autopkgtest (kadmind)[4821]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 557s 2024-06-03T13:23:12.498931+00:00 autopkgtest kadmind[4821]: kadmind: starting... 557s 2024-06-03T13:23:13.083409+00:00 autopkgtest systemd[1]: Stopping slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 557s 2024-06-03T13:23:13.107668+00:00 autopkgtest slapd[4855]: * Stopping OpenLDAP slapd 557s 2024-06-03T13:23:13.108466+00:00 autopkgtest slapd[4271]: daemon: shutdown requested and initiated. 557s 2024-06-03T13:23:13.108764+00:00 autopkgtest slapd[4271]: slapd shutdown: waiting for 0 operations/tasks to finish 557s 2024-06-03T13:23:13.110311+00:00 autopkgtest slapd[4271]: slapd stopped. 557s 2024-06-03T13:23:13.129630+00:00 autopkgtest slapd[4855]: ...done. 557s 2024-06-03T13:23:13.135589+00:00 autopkgtest systemd[1]: slapd.service: Deactivated successfully. 557s 2024-06-03T13:23:13.135666+00:00 autopkgtest systemd[1]: Stopped slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 557s 2024-06-03T13:23:13.379271+00:00 autopkgtest kernel: audit: type=1400 audit(1717420993.367:123): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="/usr/sbin/slapd" pid=4933 comm="apparmor_parser" 557s 2024-06-03T13:23:13.400088+00:00 autopkgtest systemd[1]: Reloading requested from client PID 4943 ('systemctl') (unit session-4.scope)... 557s 2024-06-03T13:23:13.404747+00:00 autopkgtest systemd[1]: Reloading... 557s 2024-06-03T13:23:13.747401+00:00 autopkgtest systemd[1]: Reloading finished in 346 ms. 557s 2024-06-03T13:23:13.883486+00:00 autopkgtest systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 557s 2024-06-03T13:23:13.904323+00:00 autopkgtest slapd[4995]: * Starting OpenLDAP slapd 557s 2024-06-03T13:23:13.916112+00:00 autopkgtest slapd[5001]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu8 (Apr 3 2024 18:47:41) $#012#011Ubuntu Developers 557s 2024-06-03T13:23:13.926760+00:00 autopkgtest slapd[5002]: slapd starting 557s 2024-06-03T13:23:13.928334+00:00 autopkgtest slapd[4995]: ...done. 557s 2024-06-03T13:23:13.934596+00:00 autopkgtest systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 557s 2024-06-03T13:23:14.033795+00:00 autopkgtest systemd[1]: Stopping slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 557s 2024-06-03T13:23:14.062206+00:00 autopkgtest slapd[5008]: * Stopping OpenLDAP slapd 557s 2024-06-03T13:23:14.063033+00:00 autopkgtest slapd[5002]: daemon: shutdown requested and initiated. 557s 2024-06-03T13:23:14.063331+00:00 autopkgtest slapd[5002]: slapd shutdown: waiting for 0 operations/tasks to finish 557s 2024-06-03T13:23:14.065181+00:00 autopkgtest slapd[5002]: slapd stopped. 557s 2024-06-03T13:23:14.084900+00:00 autopkgtest slapd[5008]: ...done. 557s 2024-06-03T13:23:14.086471+00:00 autopkgtest systemd[1]: slapd.service: Deactivated successfully. 557s 2024-06-03T13:23:14.086620+00:00 autopkgtest systemd[1]: Stopped slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 557s 2024-06-03T13:23:14.122339+00:00 autopkgtest systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 557s 2024-06-03T13:23:14.141652+00:00 autopkgtest slapd[5015]: * Starting OpenLDAP slapd 557s 2024-06-03T13:23:14.152491+00:00 autopkgtest slapd[5021]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu8 (Apr 3 2024 18:47:41) $#012#011Ubuntu Developers 557s 2024-06-03T13:23:14.163073+00:00 autopkgtest slapd[5022]: slapd starting 557s 2024-06-03T13:23:14.166017+00:00 autopkgtest slapd[5015]: ...done. 557s 2024-06-03T13:23:14.174777+00:00 autopkgtest systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 557s 2024-06-03T13:23:14.183481+00:00 autopkgtest slapd[5022]: conn=1001 op=1 RESULT tag=103 err=0 qtime=0.000012 etime=0.000386 text= 557s 2024-06-03T13:23:14.183935+00:00 autopkgtest slapd[5022]: conn=1001 op=2 MOD dn="olcDatabase={1}mdb,cn=config" 557s 2024-06-03T13:23:14.184052+00:00 autopkgtest slapd[5022]: conn=1001 op=2 MOD attr=olcAccess 557s 2024-06-03T13:23:14.184114+00:00 autopkgtest slapd[5022]: conn=1001 op=2 RESULT tag=103 err=0 qtime=0.000010 etime=0.000260 text= 557s 2024-06-03T13:23:14.184232+00:00 autopkgtest slapd[5022]: conn=1001 op=3 UNBIND 557s 2024-06-03T13:23:14.184308+00:00 autopkgtest slapd[5022]: conn=1001 fd=12 closed 557s 2024-06-03T13:23:14.202777+00:00 autopkgtest slapd[5022]: conn=1002 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 557s 2024-06-03T13:23:14.203265+00:00 autopkgtest slapd[5022]: conn=1002 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.203318+00:00 autopkgtest slapd[5022]: conn=1002 op=0 BIND authcid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" authzid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" 557s 2024-06-03T13:23:14.203365+00:00 autopkgtest slapd[5022]: conn=1002 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 557s 2024-06-03T13:23:14.203447+00:00 autopkgtest slapd[5022]: conn=1002 op=0 RESULT tag=97 err=0 qtime=0.000007 etime=0.000335 text= 557s 2024-06-03T13:23:14.204990+00:00 autopkgtest slapd[5022]: conn=1002 op=1 SRCH base="cn=config" scope=2 deref=0 filter="(cn=config)" 557s 2024-06-03T13:23:14.205022+00:00 autopkgtest slapd[5022]: conn=1002 op=1 SRCH attr=dn 557s 2024-06-03T13:23:14.205048+00:00 autopkgtest slapd[5022]: conn=1002 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000033 etime=0.000175 nentries=1 text= 557s 2024-06-03T13:23:14.205623+00:00 autopkgtest slapd[5022]: conn=1002 op=2 UNBIND 557s 2024-06-03T13:23:14.205673+00:00 autopkgtest slapd[5022]: conn=1002 fd=12 closed 557s 2024-06-03T13:23:14.322718+00:00 autopkgtest slapd[5022]: conn=1003 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 557s 2024-06-03T13:23:14.323073+00:00 autopkgtest slapd[5022]: conn=1003 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.323441+00:00 autopkgtest slapd[5022]: conn=1003 op=0 BIND authcid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" authzid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" 557s 2024-06-03T13:23:14.323523+00:00 autopkgtest slapd[5022]: conn=1003 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 557s 2024-06-03T13:23:14.323610+00:00 autopkgtest slapd[5022]: conn=1003 op=0 RESULT tag=97 err=0 qtime=0.000005 etime=0.000549 text= 557s 2024-06-03T13:23:14.324363+00:00 autopkgtest slapd[5022]: conn=1003 op=1 SRCH base="cn=schema,cn=config" scope=2 deref=0 filter="(cn={*}autofs)" 557s 2024-06-03T13:23:14.324436+00:00 autopkgtest slapd[5022]: conn=1003 op=1 SRCH attr=cn 557s 2024-06-03T13:23:14.324537+00:00 autopkgtest slapd[5022]: conn=1003 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000004 etime=0.000652 nentries=0 text= 557s 2024-06-03T13:23:14.324616+00:00 autopkgtest slapd[5022]: conn=1003 op=2 UNBIND 557s 2024-06-03T13:23:14.324672+00:00 autopkgtest slapd[5022]: conn=1003 fd=12 closed 557s 2024-06-03T13:23:14.333823+00:00 autopkgtest slapd[5022]: conn=1004 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 557s 2024-06-03T13:23:14.334341+00:00 autopkgtest slapd[5022]: conn=1004 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.334425+00:00 autopkgtest slapd[5022]: conn=1004 op=0 BIND authcid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" authzid="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" 557s 2024-06-03T13:23:14.334497+00:00 autopkgtest slapd[5022]: conn=1004 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 557s 2024-06-03T13:23:14.334555+00:00 autopkgtest slapd[5022]: conn=1004 op=0 RESULT tag=97 err=0 qtime=0.000008 etime=0.000246 text= 557s 2024-06-03T13:23:14.337682+00:00 autopkgtest slapd[5022]: conn=1004 op=1 ADD dn="cn=autofs,cn=schema,cn=config" 557s 2024-06-03T13:23:14.337738+00:00 autopkgtest slapd[5022]: conn=1004 op=1 RESULT tag=105 err=0 qtime=0.000006 etime=0.000342 text= 557s 2024-06-03T13:23:14.337873+00:00 autopkgtest slapd[5022]: conn=1004 op=2 UNBIND 557s 2024-06-03T13:23:14.337959+00:00 autopkgtest slapd[5022]: conn=1004 fd=12 closed 557s 2024-06-03T13:23:14.356310+00:00 autopkgtest slapd[5022]: conn=1005 fd=12 ACCEPT from IP=[::1]:51528 (IP=[::]:389) 557s 2024-06-03T13:23:14.356580+00:00 autopkgtest slapd[5022]: conn=1005 op=0 BIND dn="cn=admin,dc=example,dc=fake" method=128 557s 2024-06-03T13:23:14.356642+00:00 autopkgtest slapd[5022]: conn=1005 op=0 BIND dn="cn=admin,dc=example,dc=fake" mech=SIMPLE bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.356716+00:00 autopkgtest slapd[5022]: conn=1005 op=0 RESULT tag=97 err=0 qtime=0.000004 etime=0.000062 text= 557s 2024-06-03T13:23:14.357108+00:00 autopkgtest slapd[5022]: conn=1005 op=1 ADD dn="ou=auto.indirect,dc=example,dc=fake" 557s 2024-06-03T13:23:14.358435+00:00 autopkgtest slapd[5022]: conn=1005 op=1 RESULT tag=105 err=0 qtime=0.000013 etime=0.001536 text= 557s 2024-06-03T13:23:14.358667+00:00 autopkgtest slapd[5022]: conn=1005 op=2 ADD dn="cn=/,ou=auto.indirect,dc=example,dc=fake" 557s 2024-06-03T13:23:14.364147+00:00 autopkgtest slapd[5022]: conn=1005 op=3 UNBIND 557s 2024-06-03T13:23:14.364290+00:00 autopkgtest slapd[5022]: conn=1005 op=2 RESULT tag=105 err=0 qtime=0.000010 etime=0.005571 text= 557s 2024-06-03T13:23:14.364437+00:00 autopkgtest slapd[5022]: conn=1005 fd=12 closed 557s 2024-06-03T13:23:14.364495+00:00 autopkgtest slapd[5022]: connection_read(12): no connection! 557s 2024-06-03T13:23:14.413935+00:00 autopkgtest slapd[5022]: conn=1006 fd=12 ACCEPT from IP=[::1]:51536 (IP=[::]:389) 557s 2024-06-03T13:23:14.416291+00:00 autopkgtest slapd[5022]: conn=1006 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.416553+00:00 autopkgtest slapd[5022]: conn=1006 op=0 RESULT tag=97 err=14 qtime=0.000015 etime=0.001091 text=SASL(0): successful result: 557s 2024-06-03T13:23:14.429659+00:00 autopkgtest slapd[5022]: conn=1006 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.430803+00:00 autopkgtest slapd[5022]: conn=1006 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.430849+00:00 autopkgtest slapd[5022]: conn=1006 op=1 BIND dn="uid=user4766@example.fake,cn=digest-md5,cn=auth" mech=DIGEST-MD5 bind_ssf=128 ssf=128 557s 2024-06-03T13:23:14.430891+00:00 autopkgtest slapd[5022]: conn=1006 op=1 RESULT tag=97 err=0 qtime=0.000010 etime=0.005337 text= 557s 2024-06-03T13:23:14.430938+00:00 autopkgtest slapd[5022]: conn=1006 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.431211+00:00 autopkgtest slapd[5022]: conn=1006 op=2 WHOAMI 557s 2024-06-03T13:23:14.436166+00:00 autopkgtest slapd[5022]: conn=1006 op=2 RESULT oid= err=0 qtime=0.000007 etime=0.003710 text= 557s 2024-06-03T13:23:14.436324+00:00 autopkgtest slapd[5022]: conn=1006 op=3 UNBIND 557s 2024-06-03T13:23:14.436391+00:00 autopkgtest slapd[5022]: conn=1006 fd=12 closed 557s 2024-06-03T13:23:14.441812+00:00 autopkgtest slapd[5022]: conn=1007 fd=12 ACCEPT from IP=[::1]:51552 (IP=[::]:389) 557s 2024-06-03T13:23:14.449473+00:00 autopkgtest slapd[5022]: conn=1007 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.469356+00:00 autopkgtest slapd[5022]: conn=1007 op=0 RESULT tag=97 err=14 qtime=0.000005 etime=0.027207 text=SASL(0): successful result: user: user4766@example.fake property: slapAuthzDN not found in sasldb 557s 2024-06-03T13:23:14.478480+00:00 autopkgtest slapd[5022]: conn=1007 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.478659+00:00 autopkgtest slapd[5022]: conn=1007 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.478734+00:00 autopkgtest slapd[5022]: conn=1007 op=1 BIND dn="uid=user4766@example.fake,cn=scram-sha-1,cn=auth" mech=SCRAM-SHA-1 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.478836+00:00 autopkgtest slapd[5022]: conn=1007 op=1 RESULT tag=97 err=0 qtime=0.000018 etime=0.000422 text= 557s 2024-06-03T13:23:14.479121+00:00 autopkgtest slapd[5022]: conn=1007 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.479187+00:00 autopkgtest slapd[5022]: conn=1007 op=2 WHOAMI 557s 2024-06-03T13:23:14.479281+00:00 autopkgtest slapd[5022]: conn=1007 op=2 RESULT oid= err=0 qtime=0.000005 etime=0.000028 text= 557s 2024-06-03T13:23:14.479459+00:00 autopkgtest slapd[5022]: conn=1007 op=3 UNBIND 557s 2024-06-03T13:23:14.479525+00:00 autopkgtest slapd[5022]: conn=1007 fd=12 closed 557s 2024-06-03T13:23:14.491048+00:00 autopkgtest slapd[5022]: conn=1008 fd=12 ACCEPT from IP=[::1]:51554 (IP=[::]:389) 557s 2024-06-03T13:23:14.499576+00:00 autopkgtest slapd[5022]: conn=1008 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.519355+00:00 autopkgtest slapd[5022]: conn=1008 op=0 RESULT tag=97 err=14 qtime=0.000011 etime=0.027862 text=SASL(0): successful result: user: user4766@example.fake property: slapAuthzDN not found in sasldb 557s 2024-06-03T13:23:14.556001+00:00 autopkgtest slapd[5022]: conn=1008 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.556286+00:00 autopkgtest slapd[5022]: conn=1008 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.556382+00:00 autopkgtest slapd[5022]: conn=1008 op=1 BIND dn="uid=user4766@example.fake,cn=scram-sha-224,cn=auth" mech=SCRAM-SHA-224 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.556515+00:00 autopkgtest slapd[5022]: conn=1008 op=1 RESULT tag=97 err=0 qtime=0.000031 etime=0.000646 text= 557s 2024-06-03T13:23:14.556941+00:00 autopkgtest slapd[5022]: conn=1008 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.557182+00:00 autopkgtest slapd[5022]: conn=1008 op=2 WHOAMI 557s 2024-06-03T13:23:14.557302+00:00 autopkgtest slapd[5022]: conn=1008 op=2 RESULT oid= err=0 qtime=0.000021 etime=0.000412 text= 557s 2024-06-03T13:23:14.557548+00:00 autopkgtest slapd[5022]: conn=1008 op=3 UNBIND 557s 2024-06-03T13:23:14.557699+00:00 autopkgtest slapd[5022]: conn=1008 fd=12 closed 557s 2024-06-03T13:23:14.569730+00:00 autopkgtest slapd[5022]: conn=1009 fd=12 ACCEPT from IP=[::1]:51558 (IP=[::]:389) 557s 2024-06-03T13:23:14.579541+00:00 autopkgtest slapd[5022]: conn=1009 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.610038+00:00 autopkgtest slapd[5022]: conn=1009 op=0 RESULT tag=97 err=14 qtime=0.000010 etime=0.037672 text=SASL(0): successful result: user: user4766@example.fake property: slapAuthzDN not found in sasldb 557s 2024-06-03T13:23:14.628733+00:00 autopkgtest slapd[5022]: conn=1009 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.628893+00:00 autopkgtest slapd[5022]: conn=1009 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.628969+00:00 autopkgtest slapd[5022]: conn=1009 op=1 BIND dn="uid=user4766@example.fake,cn=scram-sha-256,cn=auth" mech=SCRAM-SHA-256 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.629051+00:00 autopkgtest slapd[5022]: conn=1009 op=1 RESULT tag=97 err=0 qtime=0.000010 etime=0.000373 text= 557s 2024-06-03T13:23:14.629201+00:00 autopkgtest slapd[5022]: conn=1009 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.629286+00:00 autopkgtest slapd[5022]: conn=1009 op=2 WHOAMI 557s 2024-06-03T13:23:14.629362+00:00 autopkgtest slapd[5022]: conn=1009 op=2 RESULT oid= err=0 qtime=0.000010 etime=0.000173 text= 557s 2024-06-03T13:23:14.629579+00:00 autopkgtest slapd[5022]: conn=1009 op=3 UNBIND 557s 2024-06-03T13:23:14.629690+00:00 autopkgtest slapd[5022]: conn=1009 fd=12 closed 557s 2024-06-03T13:23:14.639885+00:00 autopkgtest slapd[5022]: conn=1010 fd=12 ACCEPT from IP=[::1]:51570 (IP=[::]:389) 557s 2024-06-03T13:23:14.641787+00:00 autopkgtest slapd[5022]: conn=1010 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.669323+00:00 autopkgtest slapd[5022]: conn=1010 op=0 RESULT tag=97 err=14 qtime=0.000006 etime=0.029002 text=SASL(0): successful result: user: user4766@example.fake property: slapAuthzDN not found in sasldb 557s 2024-06-03T13:23:14.683151+00:00 autopkgtest slapd[5022]: conn=1010 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.683423+00:00 autopkgtest slapd[5022]: conn=1010 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.683559+00:00 autopkgtest slapd[5022]: conn=1010 op=1 BIND dn="uid=user4766@example.fake,cn=scram-sha-384,cn=auth" mech=SCRAM-SHA-384 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.683623+00:00 autopkgtest slapd[5022]: conn=1010 op=1 RESULT tag=97 err=0 qtime=0.000013 etime=0.000526 text= 557s 2024-06-03T13:23:14.684859+00:00 autopkgtest slapd[5022]: conn=1010 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.684989+00:00 autopkgtest slapd[5022]: conn=1010 op=2 WHOAMI 557s 2024-06-03T13:23:14.685095+00:00 autopkgtest slapd[5022]: conn=1010 op=2 RESULT oid= err=0 qtime=0.000012 etime=0.000037 text= 557s 2024-06-03T13:23:14.686506+00:00 autopkgtest slapd[5022]: conn=1010 op=3 UNBIND 557s 2024-06-03T13:23:14.686641+00:00 autopkgtest slapd[5022]: conn=1010 fd=12 closed 557s 2024-06-03T13:23:14.695663+00:00 autopkgtest slapd[5022]: conn=1011 fd=12 ACCEPT from IP=[::1]:51576 (IP=[::]:389) 557s 2024-06-03T13:23:14.699301+00:00 autopkgtest slapd[5022]: conn=1011 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.719407+00:00 autopkgtest slapd[5022]: conn=1011 op=0 RESULT tag=97 err=14 qtime=0.000014 etime=0.022995 text=SASL(0): successful result: user: user4766@example.fake property: slapAuthzDN not found in sasldb 557s 2024-06-03T13:23:14.731709+00:00 autopkgtest slapd[5022]: conn=1011 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.731867+00:00 autopkgtest slapd[5022]: conn=1011 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.731938+00:00 autopkgtest slapd[5022]: conn=1011 op=1 BIND dn="uid=user4766@example.fake,cn=scram-sha-512,cn=auth" mech=SCRAM-SHA-512 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.732017+00:00 autopkgtest slapd[5022]: conn=1011 op=1 RESULT tag=97 err=0 qtime=0.000016 etime=0.000372 text= 557s 2024-06-03T13:23:14.732319+00:00 autopkgtest slapd[5022]: conn=1011 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.732386+00:00 autopkgtest slapd[5022]: conn=1011 op=2 WHOAMI 557s 2024-06-03T13:23:14.732444+00:00 autopkgtest slapd[5022]: conn=1011 op=2 RESULT oid= err=0 qtime=0.000010 etime=0.000028 text= 557s 2024-06-03T13:23:14.732582+00:00 autopkgtest slapd[5022]: conn=1011 op=3 UNBIND 557s 2024-06-03T13:23:14.732641+00:00 autopkgtest slapd[5022]: conn=1011 fd=12 closed 557s 2024-06-03T13:23:14.744505+00:00 autopkgtest slapd[5022]: conn=1012 fd=12 ACCEPT from IP=[::1]:51592 (IP=[::]:389) 557s 2024-06-03T13:23:14.745474+00:00 autopkgtest slapd[5022]: conn=1012 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.745679+00:00 autopkgtest slapd[5022]: conn=1012 op=0 RESULT tag=97 err=14 qtime=0.000013 etime=0.000093 text=SASL(0): successful result: 557s 2024-06-03T13:23:14.746604+00:00 autopkgtest slapd[5022]: conn=1012 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.746791+00:00 autopkgtest slapd[5022]: conn=1012 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.746823+00:00 autopkgtest slapd[5022]: conn=1012 op=1 BIND dn="uid=user4766@example.fake,cn=ntlm,cn=auth" mech=NTLM bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.748921+00:00 autopkgtest slapd[5022]: connection_input: conn=1012 deferring operation: binding 557s 2024-06-03T13:23:14.749039+00:00 autopkgtest slapd[5022]: conn=1012 op=1 RESULT tag=97 err=0 qtime=0.000041 etime=0.000899 text= 557s 2024-06-03T13:23:14.749101+00:00 autopkgtest slapd[5022]: conn=1012 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.749159+00:00 autopkgtest slapd[5022]: conn=1012 op=2 WHOAMI 557s 2024-06-03T13:23:14.749212+00:00 autopkgtest slapd[5022]: conn=1012 op=2 RESULT oid= err=0 qtime=0.000021 etime=0.001742 text= 557s 2024-06-03T13:23:14.749291+00:00 autopkgtest slapd[5022]: conn=1012 op=3 UNBIND 557s 2024-06-03T13:23:14.749352+00:00 autopkgtest slapd[5022]: conn=1012 fd=12 closed 557s 2024-06-03T13:23:14.756609+00:00 autopkgtest slapd[5022]: conn=1013 fd=12 ACCEPT from IP=[::1]:51606 (IP=[::]:389) 557s 2024-06-03T13:23:14.756855+00:00 autopkgtest slapd[5022]: conn=1013 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.756921+00:00 autopkgtest slapd[5022]: conn=1013 op=0 RESULT tag=97 err=14 qtime=0.000010 etime=0.000052 text=SASL(0): successful result: 557s 2024-06-03T13:23:14.757318+00:00 autopkgtest slapd[5022]: conn=1013 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.757381+00:00 autopkgtest slapd[5022]: conn=1013 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:14.757445+00:00 autopkgtest slapd[5022]: conn=1013 op=1 BIND dn="uid=user4766@example.fake,cn=cram-md5,cn=auth" mech=CRAM-MD5 bind_ssf=0 ssf=0 557s 2024-06-03T13:23:14.757502+00:00 autopkgtest slapd[5022]: conn=1013 op=1 RESULT tag=97 err=0 qtime=0.000005 etime=0.000258 text= 557s 2024-06-03T13:23:14.757760+00:00 autopkgtest slapd[5022]: conn=1013 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.758565+00:00 autopkgtest slapd[5022]: conn=1013 op=2 WHOAMI 557s 2024-06-03T13:23:14.758606+00:00 autopkgtest slapd[5022]: conn=1013 op=2 RESULT oid= err=0 qtime=0.000009 etime=0.000029 text= 557s 2024-06-03T13:23:14.758745+00:00 autopkgtest slapd[5022]: conn=1013 op=3 UNBIND 557s 2024-06-03T13:23:14.758813+00:00 autopkgtest slapd[5022]: conn=1013 fd=12 closed 557s 2024-06-03T13:23:14.831044+00:00 autopkgtest slapd[5022]: conn=1014 fd=12 ACCEPT from IP=[::1]:51620 (IP=[::]:389) 557s 2024-06-03T13:23:14.850470+00:00 autopkgtest slapd[5022]: conn=1014 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.850838+00:00 autopkgtest slapd[5022]: connection_input: conn=1014 deferring operation: binding 557s 2024-06-03T13:23:14.850937+00:00 autopkgtest slapd[5022]: conn=1014 op=0 RESULT tag=97 err=14 qtime=0.000020 etime=0.002263 text=SASL(0): successful result: 557s 2024-06-03T13:23:14.852116+00:00 autopkgtest slapd[5022]: conn=1014 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:14.853537+00:00 autopkgtest slapd[5022]: conn=1014 op=1 RESULT tag=97 err=14 qtime=0.000204 etime=0.001426 text=SASL(0): successful result: 557s 2024-06-03T13:23:14.855554+00:00 autopkgtest slapd[5022]: conn=1014 op=2 BIND dn="" method=163 557s 2024-06-03T13:23:14.855633+00:00 autopkgtest slapd[5022]: conn=1014 op=2 BIND authcid="user4766" authzid="user4766" 557s 2024-06-03T13:23:14.855693+00:00 autopkgtest slapd[5022]: conn=1014 op=2 BIND dn="uid=user4766,cn=gssapi,cn=auth" mech=GSSAPI bind_ssf=256 ssf=256 557s 2024-06-03T13:23:14.855752+00:00 autopkgtest slapd[5022]: conn=1014 op=2 RESULT tag=97 err=0 qtime=0.000011 etime=0.000323 text= 557s 2024-06-03T13:23:14.855810+00:00 autopkgtest slapd[5022]: conn=1014 op=3 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.855873+00:00 autopkgtest slapd[5022]: conn=1014 op=3 WHOAMI 557s 2024-06-03T13:23:14.855944+00:00 autopkgtest slapd[5022]: conn=1014 op=3 RESULT oid= err=0 qtime=0.000009 etime=0.000066 text= 557s 2024-06-03T13:23:14.856006+00:00 autopkgtest slapd[5022]: conn=1014 op=4 UNBIND 557s 2024-06-03T13:23:14.856068+00:00 autopkgtest slapd[5022]: conn=1014 fd=12 closed 557s 2024-06-03T13:23:14.865521+00:00 autopkgtest slapd[5022]: conn=1015 fd=12 ACCEPT from IP=[::1]:51630 (IP=[::]:389) 557s 2024-06-03T13:23:14.867658+00:00 autopkgtest slapd[5022]: conn=1015 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:14.867770+00:00 autopkgtest slapd[5022]: conn=1015 op=0 BIND authcid="user4766" authzid="user4766" 557s 2024-06-03T13:23:14.868049+00:00 autopkgtest slapd[5022]: conn=1015 op=0 BIND dn="uid=user4766,cn=gss-spnego,cn=auth" mech=GSS-SPNEGO bind_ssf=256 ssf=256 557s 2024-06-03T13:23:14.868278+00:00 autopkgtest slapd[5022]: conn=1015 op=0 RESULT tag=97 err=0 qtime=0.000006 etime=0.001045 text= 557s 2024-06-03T13:23:14.869297+00:00 autopkgtest slapd[5022]: conn=1015 op=1 EXT oid=1.3.6.1.4.1.4203.1.11.3 557s 2024-06-03T13:23:14.869368+00:00 autopkgtest slapd[5022]: conn=1015 op=1 WHOAMI 557s 2024-06-03T13:23:14.869429+00:00 autopkgtest slapd[5022]: conn=1015 op=1 RESULT oid= err=0 qtime=0.000007 etime=0.000049 text= 557s 2024-06-03T13:23:14.869490+00:00 autopkgtest slapd[5022]: conn=1015 op=2 UNBIND 557s 2024-06-03T13:23:14.869554+00:00 autopkgtest slapd[5022]: conn=1015 fd=12 closed 557s 2024-06-03T13:23:14.891873+00:00 autopkgtest systemd[1]: Stopping autofs.service - Automounts filesystems on demand... 557s 2024-06-03T13:23:14.924624+00:00 autopkgtest systemd[1]: net-localhost-nfspub.mount: Deactivated successfully. 557s 2024-06-03T13:23:15.362878+00:00 autopkgtest systemd[1]: autofs.service: Deactivated successfully. 557s 2024-06-03T13:23:15.363958+00:00 autopkgtest systemd[1]: Stopped autofs.service - Automounts filesystems on demand. 557s 2024-06-03T13:23:15.413455+00:00 autopkgtest (utomount)[5074]: autofs.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS 557s 2024-06-03T13:23:15.413721+00:00 autopkgtest systemd[1]: Starting autofs.service - Automounts filesystems on demand... 557s 2024-06-03T13:23:15.467218+00:00 autopkgtest systemd[1]: Started autofs.service - Automounts filesystems on demand. 557s 2024-06-03T13:23:15.485034+00:00 autopkgtest slapd[5022]: conn=1016 op=0 BIND dn="" method=163 557s 2024-06-03T13:23:15.493589+00:00 autopkgtest slapd[5022]: conn=1016 op=0 RESULT tag=97 err=14 qtime=0.000014 etime=0.000113 text=SASL(0): successful result: 557s 2024-06-03T13:23:15.493782+00:00 autopkgtest slapd[5022]: conn=1016 fd=12 ACCEPT from IP=127.0.0.1:56244 (IP=0.0.0.0:389) 557s 2024-06-03T13:23:15.500404+00:00 autopkgtest slapd[5022]: conn=1016 op=1 BIND dn="" method=163 557s 2024-06-03T13:23:15.507114+00:00 autopkgtest slapd[5022]: conn=1016 op=1 BIND authcid="user4766@example.fake" authzid="user4766@example.fake" 557s 2024-06-03T13:23:15.507278+00:00 autopkgtest slapd[5022]: conn=1016 op=1 BIND dn="uid=user4766@example.fake,cn=digest-md5,cn=auth" mech=DIGEST-MD5 bind_ssf=128 ssf=128 557s 2024-06-03T13:23:15.508747+00:00 autopkgtest slapd[5022]: connection_input: conn=1016 deferring operation: binding 557s 2024-06-03T13:23:15.508945+00:00 autopkgtest slapd[5022]: conn=1016 op=1 RESULT tag=97 err=0 qtime=0.000012 etime=0.011441 text= 557s 2024-06-03T13:23:15.510336+00:00 autopkgtest slapd[5022]: conn=1016 op=2 SRCH base="ou=auto.indirect,dc=example,dc=fake" scope=2 deref=0 filter="(objectClass=nisMap)" 557s 2024-06-03T13:23:15.510881+00:00 autopkgtest slapd[5022]: conn=1016 op=2 SRCH attr=nisMapName 557s 2024-06-03T13:23:15.512593+00:00 autopkgtest slapd[5022]: conn=1016 op=2 SEARCH RESULT tag=101 err=0 qtime=0.000731 etime=0.002355 nentries=0 text= 557s 2024-06-03T13:23:15.512766+00:00 autopkgtest slapd[5022]: conn=1016 op=3 SRCH base="ou=auto.indirect,dc=example,dc=fake" scope=2 deref=0 filter="(objectClass=automountMap)" 557s 2024-06-03T13:23:15.512828+00:00 autopkgtest slapd[5022]: conn=1016 op=3 SRCH attr=ou 557s 2024-06-03T13:23:15.514326+00:00 autopkgtest slapd[5022]: conn=1016 op=3 SEARCH RESULT tag=101 err=0 qtime=0.000016 etime=0.000209 nentries=1 text= 557s 2024-06-03T13:23:15.517843+00:00 autopkgtest slapd[5022]: conn=1016 op=4 SRCH base="ou=auto.indirect,dc=example,dc=fake" scope=2 deref=0 filter="(&(objectClass=automount)(|(cn=storage)(cn=/)(cn=\2A)))" 557s 2024-06-03T13:23:15.517886+00:00 autopkgtest slapd[5022]: conn=1016 op=4 SRCH attr=cn automountInformation 557s 2024-06-03T13:23:15.517931+00:00 autopkgtest slapd[5022]: conn=1016 op=4 SEARCH RESULT tag=101 err=0 qtime=0.000011 etime=0.000125 nentries=1 text= 557s 2024-06-03T13:23:15.517969+00:00 autopkgtest slapd[5022]: conn=1016 op=5 UNBIND 557s 2024-06-03T13:23:15.518023+00:00 autopkgtest slapd[5022]: conn=1016 fd=12 closed 557s 2024-06-03T13:23:15.539994+00:00 autopkgtest kernel: netfs: FS-Cache loaded 557s 2024-06-03T13:23:15.965037+00:00 autopkgtest kernel: NFS: Registering the id_resolver key type 557s 2024-06-03T13:23:15.965265+00:00 autopkgtest kernel: Key type id_resolver registered 557s 2024-06-03T13:23:15.965278+00:00 autopkgtest kernel: Key type id_legacy registered 557s 2024-06-03T13:23:15.987806+00:00 autopkgtest rpc.mountd[3280]: v4.0 client detached: (null) from (null) 557s 2024-06-03T13:23:16.039272+00:00 autopkgtest rpc.mountd[3280]: v4.2 client detached: 0x665dc39474643615 from "127.0.0.1:968" 557s 557s ## mounts: 557s sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) 557s proc on /proc type proc (rw,nosuid,nodev,noexec,relatime) 557s udev on /dev type devtmpfs (rw,nosuid,relatime,size=746408k,nr_inodes=186602,mode=755,inode64) 557s devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) 557s tmpfs on /run type tmpfs (rw,nosuid,nodev,noexec,relatime,size=151664k,mode=755,inode64) 557s /dev/vda1 on / type ext4 (rw,relatime,discard,errors=remount-ro,commit=30) 557s securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) 557s tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,inode64) 557s tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k,inode64) 557s cgroup2 on /sys/fs/cgroup type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot) 557s bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) 557s systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=1860) 557s mqueue on /dev/mqueue type mqueue (rw,nosuid,nodev,noexec,relatime) 557s hugetlbfs on /dev/hugepages type hugetlbfs (rw,nosuid,nodev,relatime,pagesize=1M) 557s debugfs on /sys/kernel/debug type debugfs (rw,nosuid,nodev,noexec,relatime) 557s tracefs on /sys/kernel/tracing type tracefs (rw,nosuid,nodev,noexec,relatime) 557s fusectl on /sys/fs/fuse/connections type fusectl (rw,nosuid,nodev,noexec,relatime) 557s configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime) 557s binfmt_misc on /proc/sys/fs/binfmt_misc type binfmt_misc (rw,nosuid,nodev,noexec,relatime) 557s tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=151660k,nr_inodes=37915,mode=700,uid=1000,gid=1000,inode64) 557s sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) 557s nfsd on /proc/fs/nfsd type nfsd (rw,relatime) 557s ldap://server.example.fake/ou=auto.indirect,dc=example,dc=fake on /mnt type autofs (rw,relatime,fd=7,pgrp=5075,timeout=300,minproto=5,maxproto=5,indirect,pipe_ino=23541) 558s autopkgtest [13:23:17]: test ldap-map-sasl-auth: -----------------------] 558s autopkgtest [13:23:17]: test ldap-map-sasl-auth: - - - - - - - - - - results - - - - - - - - - - 558s ldap-map-sasl-auth FAIL non-zero exit status 2 558s autopkgtest [13:23:17]: test ldap-map-sasl-auth: - - - - - - - - - - stderr - - - - - - - - - - 558s ls: cannot access '/mnt/storage/test_file_4766': No such file or directory 559s autopkgtest [13:23:18]: @@@@@@@@@@@@@@@@@@@@ summary 559s smb-mount PASS 559s nfs-mount PASS 559s ldap-map-sasl-auth FAIL non-zero exit status 2 575s Using flock in scalingstack-bos02-s390x 575s flock: timeout while waiting to get lock 575s Creating nova instance adt-oracular-s390x-autofs-20240603-131359-juju-7f2275-prod-proposed-migration-environment-2-60ba083b-60a1-479a-93d4-7532f12f64fd from image adt/ubuntu-oracular-s390x-server-20240601.img (UUID abd59ec3-74c6-41dc-ad7e-cdf38d98b9b3)... 575s Using flock in scalingstack-bos02-s390x 575s Creating nova instance adt-oracular-s390x-autofs-20240603-131359-juju-7f2275-prod-proposed-migration-environment-2-60ba083b-60a1-479a-93d4-7532f12f64fd from image adt/ubuntu-oracular-s390x-server-20240601.img (UUID abd59ec3-74c6-41dc-ad7e-cdf38d98b9b3)...