0s autopkgtest [21:29:54]: starting date and time: 2024-03-23 21:29:54+0000 0s autopkgtest [21:29:54]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:29:54]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.x6kkd08s/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:samba,src:cups,src:db5.3,src:gnutls28,src:gpgme1.0,src:libarchive,src:libtirpc,src:nettle,src:openssl,src:python3.12,src:readline,src:sssd,src:tevent --apt-upgrade autofs --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=samba/2:4.19.5+dfsg-4ubuntu7 cups/2.4.7-1.2ubuntu1 db5.3/5.3.28+dfsg2-6 gnutls28/3.8.3-1.1ubuntu2 gpgme1.0/1.18.0-4.1ubuntu3 libarchive/3.7.2-1.1ubuntu2 libtirpc/1.3.4+ds-1.1 nettle/3.9.1-2.2 openssl/3.0.13-0ubuntu2 python3.12/3.12.2-4build3 readline/8.2-4 sssd/2.9.4-1.1ubuntu3 tevent/0.16.1-2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-s390x-13.secgroup --name adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 89s autopkgtest [21:31:23]: testbed dpkg architecture: s390x 89s autopkgtest [21:31:23]: testbed apt version: 2.7.12 89s autopkgtest [21:31:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 90s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 90s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 91s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB] 91s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 91s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3975 kB] 92s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [652 kB] 92s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 92s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 92s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 92s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4172 kB] 93s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 93s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB] 93s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 95s Fetched 9533 kB in 4s (2359 kB/s) 95s Reading package lists... 98s Reading package lists... 100s Building dependency tree... 100s Reading state information... 100s Calculating upgrade... 100s The following packages will be REMOVED: 100s libreadline8 libssl3 100s The following NEW packages will be installed: 100s libreadline8t64 libssl3t64 100s The following packages have been kept back: 100s libpython3.12-minimal libpython3.12-stdlib python3.12 python3.12-minimal 100s The following packages will be upgraded: 100s libtirpc-common openssl readline-common 100s 3 upgraded, 2 newly installed, 2 to remove and 4 not upgraded. 100s Need to get 2919 kB of archives. 100s After this operation, 257 kB of additional disk space will be used. 100s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x readline-common all 8.2-4 [56.4 kB] 100s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libreadline8t64 s390x 8.2-4 [170 kB] 100s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 100s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 100s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libtirpc-common all 1.3.4+ds-1.1 [8018 B] 100s Fetched 2919 kB in 1s (3755 kB/s) 100s (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 ... 52170 files and directories currently installed.) 100s Preparing to unpack .../readline-common_8.2-4_all.deb ... 100s Unpacking readline-common (8.2-4) over (8.2-3) ... 100s dpkg: libreadline8:s390x: dependency problems, but removing anyway as you requested: 100s parted depends on libreadline8 (>= 6.0). 100s libpython3.12-stdlib:s390x depends on libreadline8 (>= 7.0~beta). 100s libpython3.11-stdlib:s390x depends on libreadline8 (>= 7.0~beta). 100s gpgsm depends on libreadline8 (>= 6.0). 100s gpgconf depends on libreadline8 (>= 6.0). 100s gpg depends on libreadline8 (>= 6.0). 100s gawk depends on libreadline8 (>= 6.0). 100s fdisk depends on libreadline8 (>= 6.0). 100s 100s (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 ... 52170 files and directories currently installed.) 100s Removing libreadline8:s390x (8.2-3) ... 100s Selecting previously unselected package libreadline8t64:s390x. 100s (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 ... 52158 files and directories currently installed.) 100s Preparing to unpack .../libreadline8t64_8.2-4_s390x.deb ... 100s Adding 'diversion of /lib/s390x-linux-gnu/libhistory.so.8 to /lib/s390x-linux-gnu/libhistory.so.8.usr-is-merged by libreadline8t64' 100s Adding 'diversion of /lib/s390x-linux-gnu/libhistory.so.8.2 to /lib/s390x-linux-gnu/libhistory.so.8.2.usr-is-merged by libreadline8t64' 100s Adding 'diversion of /lib/s390x-linux-gnu/libreadline.so.8 to /lib/s390x-linux-gnu/libreadline.so.8.usr-is-merged by libreadline8t64' 100s Adding 'diversion of /lib/s390x-linux-gnu/libreadline.so.8.2 to /lib/s390x-linux-gnu/libreadline.so.8.2.usr-is-merged by libreadline8t64' 100s Unpacking libreadline8t64:s390x (8.2-4) ... 100s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 100s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 100s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 100s wget depends on libssl3 (>= 3.0.0). 100s tnftp depends on libssl3 (>= 3.0.0). 100s tcpdump depends on libssl3 (>= 3.0.0). 100s systemd-resolved depends on libssl3 (>= 3.0.0). 100s systemd depends on libssl3 (>= 3.0.0). 100s sudo depends on libssl3 (>= 3.0.0). 100s s390-tools depends on libssl3 (>= 3.0.0). 100s rsync depends on libssl3 (>= 3.0.0). 100s python3-cryptography depends on libssl3 (>= 3.0.0). 100s openssh-server depends on libssl3 (>= 3.0.10). 100s openssh-client depends on libssl3 (>= 3.0.10). 100s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 100s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 100s libssh-4:s390x depends on libssl3 (>= 3.0.0). 100s libsasl2-modules:s390x depends on libssl3 (>= 3.0.0). 100s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 100s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 100s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 100s libnvme1 depends on libssl3 (>= 3.0.0). 100s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 100s libkmod2:s390x depends on libssl3 (>= 3.0.0). 100s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 100s libcurl4:s390x depends on libssl3 (>= 3.0.0). 100s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 100s kmod depends on libssl3 (>= 3.0.0). 100s dhcpcd-base depends on libssl3 (>= 3.0.0). 100s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 100s 100s (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 ... 52178 files and directories currently installed.) 100s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 100s Selecting previously unselected package libssl3t64:s390x. 100s (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 ... 52167 files and directories currently installed.) 100s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 100s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 100s Preparing to unpack .../libtirpc-common_1.3.4+ds-1.1_all.deb ... 100s Unpacking libtirpc-common (1.3.4+ds-1.1) over (1.3.4+ds-1build1) ... 100s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 100s Setting up libtirpc-common (1.3.4+ds-1.1) ... 100s Setting up openssl (3.0.13-0ubuntu2) ... 100s Setting up readline-common (8.2-4) ... 100s Setting up libreadline8t64:s390x (8.2-4) ... 100s Processing triggers for libc-bin (2.39-0ubuntu6) ... 100s Processing triggers for man-db (2.12.0-3) ... 101s Processing triggers for install-info (7.1-3) ... 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 102s 0 upgraded, 0 newly installed, 0 to remove and 4 not upgraded. 102s Unknown architecture, assuming PC-style ttyS0 102s sh: Attempting to set up Debian/Ubuntu apt sources automatically 102s sh: Distribution appears to be Ubuntu 103s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s eatmydata is already the newest version (131-1). 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s dbus is already the newest version (1.14.10-4ubuntu1). 104s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s rng-tools-debian is already the newest version (2.4). 104s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s Reading package lists... 104s Building dependency tree... 104s Reading state information... 105s The following packages will be REMOVED: 105s cloud-init* python3-configobj* python3-debconf* 105s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 105s After this operation, 3256 kB disk space will be freed. 105s (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 ... 52180 files and directories currently installed.) 105s Removing cloud-init (24.1.2-0ubuntu1) ... 105s Removing python3-configobj (5.0.8-3) ... 105s Removing python3-debconf (1.5.86) ... 105s Processing triggers for man-db (2.12.0-3) ... 106s (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 ... 51791 files and directories currently installed.) 106s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 106s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 106s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 106s invoke-rc.d: policy-rc.d denied execution of try-restart. 107s Reading package lists... 107s Building dependency tree... 107s Reading state information... 107s linux-generic is already the newest version (6.8.0-11.11+1). 107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 107s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 107s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 108s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 109s Reading package lists... 109s Reading package lists... 109s Building dependency tree... 109s Reading state information... 110s Calculating upgrade... 110s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Reading package lists... 110s Building dependency tree... 110s Reading state information... 110s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s autopkgtest [21:31:44]: rebooting testbed after setup commands that affected boot 125s autopkgtest [21:31:59]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 127s autopkgtest [21:32:01]: @@@@@@@@@@@@@@@@@@@@ apt-source autofs 130s Get:1 http://ftpmaster.internal/ubuntu noble/main autofs 5.1.9-1ubuntu1 (dsc) [2552 B] 130s Get:2 http://ftpmaster.internal/ubuntu noble/main autofs 5.1.9-1ubuntu1 (tar) [332 kB] 130s Get:3 http://ftpmaster.internal/ubuntu noble/main autofs 5.1.9-1ubuntu1 (diff) [27.6 kB] 130s gpgv: Signature made Thu Feb 29 14:07:09 2024 UTC 130s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 130s gpgv: Can't check signature: No public key 130s dpkg-source: warning: cannot verify inline signature for ./autofs_5.1.9-1ubuntu1.dsc: no acceptable signature found 130s autopkgtest [21:32:04]: testing package autofs version 5.1.9-1ubuntu1 131s autopkgtest [21:32:05]: build not needed 135s autopkgtest [21:32:09]: test smb-mount: preparing testbed 142s Reading package lists... 142s Building dependency tree... 142s Reading state information... 142s Starting pkgProblemResolver with broken count: 0 142s Starting 2 pkgProblemResolver with broken count: 0 142s Done 142s The following additional packages will be installed: 142s autofs autofs-hesiod autofs-ldap cifs-utils libavahi-client3 142s libavahi-common-data libavahi-common3 libcups2 libhesiod0 libidn12 libldb2 142s libpython3.12 libsmbclient libtalloc2 libtdb1 libtevent0 libwbclient0 142s python3-dnspython python3-ldb python3-markdown python3-samba python3-talloc 142s python3-tdb samba samba-common samba-common-bin samba-libs smbclient 142s tdb-tools 142s Suggested packages: 142s winbind cups-common python3-trio python3-aioquic python3-h2 python3-httpx 142s python3-httpcore python-markdown-doc bind9 bind9utils ctdb ldb-tools ntp 142s | chrony heimdal-clients 142s Recommended packages: 142s nfs-common keyutils python3-gpg attr samba-dsdb-modules samba-vfs-modules 142s samba-ad-provision 142s The following NEW packages will be installed: 142s autofs autofs-hesiod autofs-ldap autopkgtest-satdep cifs-utils 142s libavahi-client3 libavahi-common-data libavahi-common3 libcups2 libhesiod0 142s libidn12 libldb2 libpython3.12 libsmbclient libtalloc2 libtdb1 libtevent0 142s libwbclient0 python3-dnspython python3-ldb python3-markdown python3-samba 142s python3-talloc python3-tdb samba samba-common samba-common-bin samba-libs 142s smbclient tdb-tools 142s 0 upgraded, 30 newly installed, 0 to remove and 0 not upgraded. 142s Need to get 16.7 MB/16.7 MB of archives. 142s After this operation, 77.6 MB of additional disk space will be used. 142s Get:1 /tmp/autopkgtest.us1X95/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [740 B] 142s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x python3-dnspython all 2.6.1-1ubuntu1 [163 kB] 143s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libtalloc2 s390x 2.4.2-1 [28.3 kB] 143s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libtdb1 s390x 1.4.10-1 [49.9 kB] 143s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libtevent0 s390x 0.16.1-1 [43.1 kB] 143s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libldb2 s390x 2:2.8.0+samba4.19.5+dfsg-1ubuntu1 [191 kB] 143s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x libpython3.12 s390x 3.12.2-1 [2561 kB] 143s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x python3-ldb s390x 2:2.8.0+samba4.19.5+dfsg-1ubuntu1 [42.3 kB] 143s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x python3-tdb s390x 1.4.10-1 [15.9 kB] 143s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x python3-markdown all 3.5.2-1 [72.0 kB] 143s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x libavahi-common-data s390x 0.8-13ubuntu2 [29.5 kB] 143s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x libavahi-common3 s390x 0.8-13ubuntu2 [23.8 kB] 143s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libavahi-client3 s390x 0.8-13ubuntu2 [26.7 kB] 143s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x libwbclient0 s390x 2:4.19.5+dfsg-1ubuntu1 [70.3 kB] 143s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x samba-libs s390x 2:4.19.5+dfsg-1ubuntu1 [6231 kB] 144s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x python3-talloc s390x 2.4.2-1 [12.9 kB] 144s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x python3-samba s390x 2:4.19.5+dfsg-1ubuntu1 [3398 kB] 144s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x samba-common all 2:4.19.5+dfsg-1ubuntu1 [63.4 kB] 144s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x libcups2 s390x 2.4.6-0ubuntu3 [277 kB] 144s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x samba-common-bin s390x 2:4.19.5+dfsg-1ubuntu1 [1302 kB] 144s Get:21 http://ftpmaster.internal/ubuntu noble/main s390x tdb-tools s390x 1.4.10-1 [26.5 kB] 144s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x samba s390x 2:4.19.5+dfsg-1ubuntu1 [1015 kB] 144s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x autofs s390x 5.1.9-1ubuntu1 [293 kB] 144s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x cifs-utils s390x 2:7.0-2 [95.3 kB] 144s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x libidn12 s390x 1.42-1 [59.2 kB] 144s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libsmbclient s390x 2:4.19.5+dfsg-1ubuntu1 [65.1 kB] 144s Get:27 http://ftpmaster.internal/ubuntu noble/main s390x smbclient s390x 2:4.19.5+dfsg-1ubuntu1 [494 kB] 144s Get:28 http://ftpmaster.internal/ubuntu noble/universe s390x libhesiod0 s390x 3.2.1-4 [22.3 kB] 144s Get:29 http://ftpmaster.internal/ubuntu noble/universe s390x autofs-hesiod s390x 5.1.9-1ubuntu1 [10.6 kB] 144s Get:30 http://ftpmaster.internal/ubuntu noble/universe s390x autofs-ldap s390x 5.1.9-1ubuntu1 [44.3 kB] 144s Preconfiguring packages ... 144s Fetched 16.7 MB in 2s (9073 kB/s) 144s Selecting previously unselected package python3-dnspython. 145s (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 ... 51736 files and directories currently installed.) 145s Preparing to unpack .../00-python3-dnspython_2.6.1-1ubuntu1_all.deb ... 145s Unpacking python3-dnspython (2.6.1-1ubuntu1) ... 145s Selecting previously unselected package libtalloc2:s390x. 145s Preparing to unpack .../01-libtalloc2_2.4.2-1_s390x.deb ... 145s Unpacking libtalloc2:s390x (2.4.2-1) ... 145s Selecting previously unselected package libtdb1:s390x. 145s Preparing to unpack .../02-libtdb1_1.4.10-1_s390x.deb ... 145s Unpacking libtdb1:s390x (1.4.10-1) ... 145s Selecting previously unselected package libtevent0:s390x. 145s Preparing to unpack .../03-libtevent0_0.16.1-1_s390x.deb ... 145s Unpacking libtevent0:s390x (0.16.1-1) ... 145s Selecting previously unselected package libldb2:s390x. 145s Preparing to unpack .../04-libldb2_2%3a2.8.0+samba4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking libldb2:s390x (2:2.8.0+samba4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package libpython3.12:s390x. 145s Preparing to unpack .../05-libpython3.12_3.12.2-1_s390x.deb ... 145s Unpacking libpython3.12:s390x (3.12.2-1) ... 145s Selecting previously unselected package python3-ldb. 145s Preparing to unpack .../06-python3-ldb_2%3a2.8.0+samba4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking python3-ldb (2:2.8.0+samba4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package python3-tdb. 145s Preparing to unpack .../07-python3-tdb_1.4.10-1_s390x.deb ... 145s Unpacking python3-tdb (1.4.10-1) ... 145s Selecting previously unselected package python3-markdown. 145s Preparing to unpack .../08-python3-markdown_3.5.2-1_all.deb ... 145s Unpacking python3-markdown (3.5.2-1) ... 145s Selecting previously unselected package libavahi-common-data:s390x. 145s Preparing to unpack .../09-libavahi-common-data_0.8-13ubuntu2_s390x.deb ... 145s Unpacking libavahi-common-data:s390x (0.8-13ubuntu2) ... 145s Selecting previously unselected package libavahi-common3:s390x. 145s Preparing to unpack .../10-libavahi-common3_0.8-13ubuntu2_s390x.deb ... 145s Unpacking libavahi-common3:s390x (0.8-13ubuntu2) ... 145s Selecting previously unselected package libavahi-client3:s390x. 145s Preparing to unpack .../11-libavahi-client3_0.8-13ubuntu2_s390x.deb ... 145s Unpacking libavahi-client3:s390x (0.8-13ubuntu2) ... 145s Selecting previously unselected package libwbclient0:s390x. 145s Preparing to unpack .../12-libwbclient0_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking libwbclient0:s390x (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package samba-libs:s390x. 145s Preparing to unpack .../13-samba-libs_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking samba-libs:s390x (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package python3-talloc:s390x. 145s Preparing to unpack .../14-python3-talloc_2.4.2-1_s390x.deb ... 145s Unpacking python3-talloc:s390x (2.4.2-1) ... 145s Selecting previously unselected package python3-samba. 145s Preparing to unpack .../15-python3-samba_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking python3-samba (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package samba-common. 145s Preparing to unpack .../16-samba-common_2%3a4.19.5+dfsg-1ubuntu1_all.deb ... 145s Unpacking samba-common (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package libcups2:s390x. 145s Preparing to unpack .../17-libcups2_2.4.6-0ubuntu3_s390x.deb ... 145s Unpacking libcups2:s390x (2.4.6-0ubuntu3) ... 145s Selecting previously unselected package samba-common-bin. 145s Preparing to unpack .../18-samba-common-bin_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking samba-common-bin (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package tdb-tools. 145s Preparing to unpack .../19-tdb-tools_1.4.10-1_s390x.deb ... 145s Unpacking tdb-tools (1.4.10-1) ... 145s Selecting previously unselected package samba. 145s Preparing to unpack .../20-samba_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 145s Unpacking samba (2:4.19.5+dfsg-1ubuntu1) ... 145s Selecting previously unselected package autofs. 145s Preparing to unpack .../21-autofs_5.1.9-1ubuntu1_s390x.deb ... 145s Unpacking autofs (5.1.9-1ubuntu1) ... 146s Selecting previously unselected package cifs-utils. 146s Preparing to unpack .../22-cifs-utils_2%3a7.0-2_s390x.deb ... 146s Unpacking cifs-utils (2:7.0-2) ... 146s Selecting previously unselected package libidn12:s390x. 146s Preparing to unpack .../23-libidn12_1.42-1_s390x.deb ... 146s Unpacking libidn12:s390x (1.42-1) ... 146s Selecting previously unselected package libsmbclient:s390x. 146s Preparing to unpack .../24-libsmbclient_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 146s Unpacking libsmbclient:s390x (2:4.19.5+dfsg-1ubuntu1) ... 146s Selecting previously unselected package smbclient. 146s Preparing to unpack .../25-smbclient_2%3a4.19.5+dfsg-1ubuntu1_s390x.deb ... 146s Unpacking smbclient (2:4.19.5+dfsg-1ubuntu1) ... 146s Selecting previously unselected package libhesiod0:s390x. 146s Preparing to unpack .../26-libhesiod0_3.2.1-4_s390x.deb ... 146s Unpacking libhesiod0:s390x (3.2.1-4) ... 146s Selecting previously unselected package autofs-hesiod. 146s Preparing to unpack .../27-autofs-hesiod_5.1.9-1ubuntu1_s390x.deb ... 146s Unpacking autofs-hesiod (5.1.9-1ubuntu1) ... 146s Selecting previously unselected package autofs-ldap. 146s Preparing to unpack .../28-autofs-ldap_5.1.9-1ubuntu1_s390x.deb ... 146s Unpacking autofs-ldap (5.1.9-1ubuntu1) ... 146s Selecting previously unselected package autopkgtest-satdep. 146s Preparing to unpack .../29-1-autopkgtest-satdep.deb ... 146s Unpacking autopkgtest-satdep (0) ... 146s Setting up libtdb1:s390x (1.4.10-1) ... 146s Setting up samba-common (2:4.19.5+dfsg-1ubuntu1) ... 146s 146s Creating config file /etc/samba/smb.conf with new version 146s Setting up libwbclient0:s390x (2:4.19.5+dfsg-1ubuntu1) ... 146s Setting up libtalloc2:s390x (2.4.2-1) ... 146s Setting up python3-tdb (1.4.10-1) ... 146s Setting up libtevent0:s390x (0.16.1-1) ... 146s Setting up libavahi-common-data:s390x (0.8-13ubuntu2) ... 146s Setting up tdb-tools (1.4.10-1) ... 146s update-alternatives: using /usr/bin/tdbbackup.tdbtools to provide /usr/bin/tdbbackup (tdbbackup) in auto mode 146s Setting up libidn12:s390x (1.42-1) ... 146s Setting up python3-markdown (3.5.2-1) ... 146s Setting up python3-dnspython (2.6.1-1ubuntu1) ... 147s Setting up libldb2:s390x (2:2.8.0+samba4.19.5+dfsg-1ubuntu1) ... 147s Setting up libpython3.12:s390x (3.12.2-1) ... 147s Setting up autofs (5.1.9-1ubuntu1) ... 147s 147s Creating config file /etc/auto.master with new version 147s 147s Creating config file /etc/auto.net with new version 147s 147s Creating config file /etc/auto.misc with new version 147s 147s Creating config file /etc/auto.smb with new version 148s 148s Creating config file /etc/autofs.conf with new version 148s 148s Creating config file /etc/default/autofs with new version 148s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 148s Created symlink /etc/systemd/system/multi-user.target.wants/autofs.service → /usr/lib/systemd/system/autofs.service. 149s Setting up python3-talloc:s390x (2.4.2-1) ... 149s Setting up cifs-utils (2:7.0-2) ... 149s update-alternatives: using /usr/lib/s390x-linux-gnu/cifs-utils/idmapwb.so to provide /etc/cifs-utils/idmap-plugin (idmap-plugin) in auto mode 149s Setting up libavahi-common3:s390x (0.8-13ubuntu2) ... 149s Setting up libhesiod0:s390x (3.2.1-4) ... 149s Setting up autofs-ldap (5.1.9-1ubuntu1) ... 149s Setting up python3-ldb (2:2.8.0+samba4.19.5+dfsg-1ubuntu1) ... 149s Setting up libavahi-client3:s390x (0.8-13ubuntu2) ... 149s Setting up autofs-hesiod (5.1.9-1ubuntu1) ... 149s Setting up samba-libs:s390x (2:4.19.5+dfsg-1ubuntu1) ... 149s Setting up libsmbclient:s390x (2:4.19.5+dfsg-1ubuntu1) ... 149s Setting up smbclient (2:4.19.5+dfsg-1ubuntu1) ... 149s Setting up libcups2:s390x (2.4.6-0ubuntu3) ... 149s Setting up python3-samba (2:4.19.5+dfsg-1ubuntu1) ... 150s /usr/lib/python3/dist-packages/samba/tests/dns_forwarder_helpers/server.py:80: SyntaxWarning: invalid escape sequence '\s' 150s m = re.match(b'^timeout\s+([\d.]+)$', data.strip()) 150s /usr/lib/python3/dist-packages/samba/tests/samba_tool/join_member.py:43: SyntaxWarning: invalid escape sequence '\s' 150s existing_records = re.findall('A:\s+(\d+\.\d+\.\d+\.\d+)\s', out) 150s /usr/lib/python3/dist-packages/samba/tests/samba_tool/ntacl.py:93: SyntaxWarning: invalid escape sequence '\s' 150s self.assertNotRegex(err, '^\s*File [^,]+, line \d+, in', 150s /usr/lib/python3/dist-packages/samba/tests/samba_tool/user_virtualCryptSHA.py:42: SyntaxWarning: invalid escape sequence '\s' 150s p = re.compile("^" + name + ":\s+(\S+)") 150s /usr/lib/python3/dist-packages/samba/tests/samba_tool/user_virtualCryptSHA_base.py:42: SyntaxWarning: invalid escape sequence '\s' 150s p = re.compile("^" + name + ":\s+(\S+)") 150s Setting up samba-common-bin (2:4.19.5+dfsg-1ubuntu1) ... 150s Setting up samba (2:4.19.5+dfsg-1ubuntu1) ... 151s Created symlink /etc/systemd/system/smb.service → /usr/lib/systemd/system/smbd.service. 151s Created symlink /etc/systemd/system/multi-user.target.wants/smbd.service → /usr/lib/systemd/system/smbd.service. 152s Created symlink /etc/systemd/system/nmb.service → /usr/lib/systemd/system/nmbd.service. 152s Created symlink /etc/systemd/system/multi-user.target.wants/nmbd.service → /usr/lib/systemd/system/nmbd.service. 153s Created symlink /etc/systemd/system/samba.service → /usr/lib/systemd/system/samba-ad-dc.service. 153s Created symlink /etc/systemd/system/multi-user.target.wants/samba-ad-dc.service → /usr/lib/systemd/system/samba-ad-dc.service. 153s Setting up autopkgtest-satdep (0) ... 153s Processing triggers for ufw (0.36.2-5) ... 153s Processing triggers for man-db (2.12.0-3) ... 154s Processing triggers for libc-bin (2.39-0ubuntu6) ... 157s (Reading database ... 53098 files and directories currently installed.) 157s Removing autopkgtest-satdep (0) ... 157s autopkgtest [21:32:31]: test smb-mount: [----------------------- 157s + date --utc 157s + now=Sat Mar 23 21:32:31 UTC 2024 157s + result=0 157s + systemctl restart autofs 157s + echo Setting up a public samba share in /pub 157s + mkdir -p /pub 157s + echo Sat Mar 23 21:32:31 UTC 2024 - This is the public samba share. 157s + add_smb_share pub /pub yes 157s + local share=pub 157s + local sharepath=/pub 157s + local public=yes 157s Setting up a public samba share in /pub 157s + + testparm -sgrep -qE ^\[pub\] 157s 157s + echo Adding [pub] share 157s Adding [pub] share 157s + cat 157s + [ yes = yes ] 157s + cat 157s + systemctl reload smbd.service 157s + echo Setting up a private samba share in /private 157s + mkdir -pSetting up a private samba share in /private 157s /private 157s + echo Sat Mar 23 21:32:31 UTC 2024 - This is the private samba share. 157s + add_smb_share private /private no 157s + local share=private 157s + local sharepath=/private 157s + local public=no 157s + + testparm -s 157s grep -qE ^\[private\] 158s + echo Adding [private] share 158s + catAdding [private] share 158s 158s + [ no = yes ] 158s + cat 158s + systemctl reload smbd.service 158s + username=smbtest2689 158s + password=2689 158s + echoCreating a local test user called smbtest2689 158s Creating a local test user called smbtest2689 158s + create_user smbtest2689 2689 158s + local username=smbtest2689 158s + local password=2689 158s + useradd -m smbtest2689 158s + echo Setting samba password for the smbtest2689 user 158s Setting samba password for the smbtest2689 user 158s + + echo 2689 158s + smbpasswdecho 2689 158s -s -a smbtest2689 158s Added user smbtest2689. 158s + echo Setting up autofs credentials for the private share 158s + mkdir -m 0700 -p /etc/creds 158s Setting up autofs credentials for the private share 158s + cat 158s + chmod 0600 /etc/creds/localhost 158s + grep -qE ^/cifs /etc/auto.master 158s + echo Configuring autofs for the /cifs mountpoint 158s + Configuring autofs for the /cifs mountpoint 158s echo /cifs /etc/auto.smb --timeout=180 158s + systemctl restart autofs 158s Testing authenticated share automount 158s + echo Testing authenticated share automount 158s + timeout 30s grep -qE Sat Mar 23 21:32:31 UTC 2024.*private /cifs/localhost/private/hello-private.txt 158s + [ 0 -ne 0 ] 158s + echo Confirming with smbstatus that an authenticated connection was used 158s Confirming with smbstatus that an authenticated connection was used 158s + smbstatus 158s + output= 158s Samba version 4.19.5-Ubuntu 158s PID Username Group Machine Protocol Version Encryption Signing 158s ---------------------------------------------------------------------------------------------------------------------------------------- 158s 2780 smbtest2689 smbtest2689 ::1 (ipv6:::1:58498) SMB3_11 - partial(AES-128-CMAC) 158s 158s Service pid Machine Connected at Encryption Signing 158s --------------------------------------------------------------------------------------------- 158s private 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 158s IPC$ 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 158s 158s 158s Locked files: 158s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 158s -------------------------------------------------------------------------------------------------- 158s 2780 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Sat Mar 23 21:32:32 2024 158s + + echo 158s Samba version 4.19.5-Ubuntu 158s PID Username Group Machine Protocol Version Encryption Signing 158s ---------------------------------------------------------------------------------------------------------------------------------------- 158s 2780 smbtest2689 smbtest2689 ::1 (ipv6:::1:58498) SMB3_11 - partial(AES-128-CMAC) 158s 158s Service pid Machine Connected at Encryption Signing 158s --------------------------------------------------------------------------------------------- 158s private 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 158s IPC$ 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 158s 158s 158s Locked files: 158s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 158s -------------------------------------------------------------------------------------------------- 158s 2780 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Sat Mar 23 21:32:32 2024 158s grep -q smbtest2689 158s + [ 0 -ne 0 ] 158s + echo Confirming with mount -t cifs that the filesystem is mounted with authentication 158s + mount -t cifs 158s + output=//localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest2689,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) 158s + + grep -qE on /cifs/localhost/private.*username=smbtest2689 158s echo //localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest2689,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) 158s + [ 0 -ne 0 ] 158s + echo Testing unauthenticated share automount 158s + timeout 30s grep -q Sat Mar 23 21:32:31 UTC 2024.*public /cifs/127.0.0.1/pub/hello-public.txt 158s Confirming with mount -t cifs that the filesystem is mounted with authentication 158s Testing unauthenticated share automount 158s + [ 0 -ne 0 ] 158s + echo Confirming with smbstatus that a guest connection was used 158s + smbstatus 158s Confirming with smbstatus that a guest connection was used 159s + output= 159s Samba version 4.19.5-Ubuntu 159s PID Username Group Machine Protocol Version Encryption Signing 159s ---------------------------------------------------------------------------------------------------------------------------------------- 159s 2813 nobody nogroup 127.0.0.1 (ipv4:127.0.0.1:52980) SMB3_11 - - 159s 2780 smbtest2689 smbtest2689 ::1 (ipv6:::1:58498) SMB3_11 - partial(AES-128-CMAC) 159s 159s Service pid Machine Connected at Encryption Signing 159s --------------------------------------------------------------------------------------------- 159s private 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 159s IPC$ 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 159s pub 2813 127.0.0.1 Sat Mar 23 21:32:33 2024 UTC - - 159s IPC$ 2813 127.0.0.1 Sat Mar 23 21:32:33 2024 UTC - - 159s 159s 159s Locked files: 159s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 159s -------------------------------------------------------------------------------------------------- 159s 2813 65534 DENY_NONE 0x120089 RDONLY LEASE(RWH) /pub hello-public.txt Sat Mar 23 21:32:32 2024 159s 2780 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Sat Mar 23 21:32:32 2024 159s + + echogrep 159s Samba version 4.19.5-Ubuntu 159s PID Username Group Machine Protocol Version Encryption Signing 159s ---------------------------------------------------------------------------------------------------------------------------------------- 159s 2813 nobody nogroup 127.0.0.1 (ipv4:127.0.0.1:52980) SMB3_11 - - 159s 2780 smbtest2689 smbtest2689 ::1 (ipv6:::1:58498) SMB3_11 - partial(AES-128-CMAC) 159s 159s Service pid Machine Connected at Encryption Signing 159s --------------------------------------------------------------------------------------------- 159s private 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 159s IPC$ 2780 ::1 Sat Mar 23 21:32:33 2024 UTC - - 159s pub 2813 127.0.0.1 Sat Mar 23 21:32:33 2024 UTC - - 159s IPC$ 2813 127.0.0.1 Sat Mar 23 21:32:33 2024 UTC - - 159s 159s 159s Locked files: 159s Pid User(ID) DenyMode Access R/W Oplock SharePath Name Time 159s -------------------------------------------------------------------------------------------------- 159s 2813 65534 DENY_NONE 0x120089 RDONLY LEASE(RWH) /pub hello-public.txt Sat Mar 23 21:32:32 2024 159s 2780 1001 DENY_NONE 0x120089 RDONLY LEASE(RWH) /private hello-private.txt Sat Mar 23 21:32:32 2024 159s -q nobody 159s + [ 0 -ne 0 ] 159s Confirming with mount -t cifs that the filesystem is mounted without authentication 159s + echo Confirming with mount -t cifs that the filesystem is mounted without authentication 159s + mount -t cifs 159s + output=//localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest2689,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) 159s //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) 159s + + echo //localhost/private on /cifs/localhost/private type cifs (rw,relatime,vers=3.1.1,cache=strict,username=smbtest2689,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) 159s //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) 159s grep -qE on /cifs/127.0.0.1/pub.*sec=none 159s + [ 0 -ne 0 ] 159s autopkgtest [21:32:33]: test smb-mount: -----------------------] 159s autopkgtest [21:32:33]: test smb-mount: - - - - - - - - - - results - - - - - - - - - - 159s smb-mount PASS 160s autopkgtest [21:32:34]: test nfs-mount: preparing testbed 266s autopkgtest [21:34:20]: testbed dpkg architecture: s390x 266s autopkgtest [21:34:20]: testbed apt version: 2.7.12 266s autopkgtest [21:34:20]: @@@@@@@@@@@@@@@@@@@@ test bed setup 267s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 267s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 267s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3975 kB] 268s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 268s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB] 268s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [652 kB] 268s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 268s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 268s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 268s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4172 kB] 268s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 268s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB] 268s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 271s Fetched 9533 kB in 3s (3291 kB/s) 271s Reading package lists... 273s Reading package lists... 273s Building dependency tree... 273s Reading state information... 273s Calculating upgrade... 273s The following packages will be REMOVED: 273s libreadline8 libssl3 273s The following NEW packages will be installed: 273s libreadline8t64 libssl3t64 273s The following packages have been kept back: 273s libpython3.12-minimal libpython3.12-stdlib python3.12 python3.12-minimal 273s The following packages will be upgraded: 273s libtirpc-common openssl readline-common 273s 3 upgraded, 2 newly installed, 2 to remove and 4 not upgraded. 273s Need to get 2919 kB of archives. 273s After this operation, 257 kB of additional disk space will be used. 273s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x readline-common all 8.2-4 [56.4 kB] 274s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libreadline8t64 s390x 8.2-4 [170 kB] 274s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 275s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 276s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libtirpc-common all 1.3.4+ds-1.1 [8018 B] 276s Fetched 2919 kB in 3s (1158 kB/s) 276s (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 ... 52170 files and directories currently installed.) 276s Preparing to unpack .../readline-common_8.2-4_all.deb ... 276s Unpacking readline-common (8.2-4) over (8.2-3) ... 276s dpkg: libreadline8:s390x: dependency problems, but removing anyway as you requested: 276s parted depends on libreadline8 (>= 6.0). 276s libpython3.12-stdlib:s390x depends on libreadline8 (>= 7.0~beta). 276s libpython3.11-stdlib:s390x depends on libreadline8 (>= 7.0~beta). 276s gpgsm depends on libreadline8 (>= 6.0). 276s gpgconf depends on libreadline8 (>= 6.0). 276s gpg depends on libreadline8 (>= 6.0). 276s gawk depends on libreadline8 (>= 6.0). 276s fdisk depends on libreadline8 (>= 6.0). 276s 276s (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 ... 52170 files and directories currently installed.) 276s Removing libreadline8:s390x (8.2-3) ... 276s Selecting previously unselected package libreadline8t64:s390x. 276s (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 ... 52158 files and directories currently installed.) 276s Preparing to unpack .../libreadline8t64_8.2-4_s390x.deb ... 276s Adding 'diversion of /lib/s390x-linux-gnu/libhistory.so.8 to /lib/s390x-linux-gnu/libhistory.so.8.usr-is-merged by libreadline8t64' 276s Adding 'diversion of /lib/s390x-linux-gnu/libhistory.so.8.2 to /lib/s390x-linux-gnu/libhistory.so.8.2.usr-is-merged by libreadline8t64' 276s Adding 'diversion of /lib/s390x-linux-gnu/libreadline.so.8 to /lib/s390x-linux-gnu/libreadline.so.8.usr-is-merged by libreadline8t64' 276s Adding 'diversion of /lib/s390x-linux-gnu/libreadline.so.8.2 to /lib/s390x-linux-gnu/libreadline.so.8.2.usr-is-merged by libreadline8t64' 276s Unpacking libreadline8t64:s390x (8.2-4) ... 276s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 276s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 277s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 277s wget depends on libssl3 (>= 3.0.0). 277s tnftp depends on libssl3 (>= 3.0.0). 277s tcpdump depends on libssl3 (>= 3.0.0). 277s systemd-resolved depends on libssl3 (>= 3.0.0). 277s systemd depends on libssl3 (>= 3.0.0). 277s sudo depends on libssl3 (>= 3.0.0). 277s s390-tools depends on libssl3 (>= 3.0.0). 277s rsync depends on libssl3 (>= 3.0.0). 277s python3-cryptography depends on libssl3 (>= 3.0.0). 277s openssh-server depends on libssl3 (>= 3.0.10). 277s openssh-client depends on libssl3 (>= 3.0.10). 277s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 277s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 277s libssh-4:s390x depends on libssl3 (>= 3.0.0). 277s libsasl2-modules:s390x depends on libssl3 (>= 3.0.0). 277s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 277s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 277s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 277s libnvme1 depends on libssl3 (>= 3.0.0). 277s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 277s libkmod2:s390x depends on libssl3 (>= 3.0.0). 277s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 277s libcurl4:s390x depends on libssl3 (>= 3.0.0). 277s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 277s kmod depends on libssl3 (>= 3.0.0). 277s dhcpcd-base depends on libssl3 (>= 3.0.0). 277s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 277s 277s (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 ... 52178 files and directories currently installed.) 277s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 277s Selecting previously unselected package libssl3t64:s390x. 277s (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 ... 52167 files and directories currently installed.) 277s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 277s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 277s Preparing to unpack .../libtirpc-common_1.3.4+ds-1.1_all.deb ... 277s Unpacking libtirpc-common (1.3.4+ds-1.1) over (1.3.4+ds-1build1) ... 277s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 277s Setting up libtirpc-common (1.3.4+ds-1.1) ... 277s Setting up openssl (3.0.13-0ubuntu2) ... 277s Setting up readline-common (8.2-4) ... 277s Setting up libreadline8t64:s390x (8.2-4) ... 277s Processing triggers for libc-bin (2.39-0ubuntu6) ... 277s Processing triggers for man-db (2.12.0-3) ... 277s Processing triggers for install-info (7.1-3) ... 277s Reading package lists... 277s Building dependency tree... 277s Reading state information... 278s 0 upgraded, 0 newly installed, 0 to remove and 4 not upgraded. 278s Unknown architecture, assuming PC-style ttyS0 278s sh: Attempting to set up Debian/Ubuntu apt sources automatically 278s sh: Distribution appears to be Ubuntu 279s Reading package lists... 279s Building dependency tree... 279s Reading state information... 279s eatmydata is already the newest version (131-1). 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 279s Reading package lists... 279s Building dependency tree... 279s Reading state information... 279s dbus is already the newest version (1.14.10-4ubuntu1). 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 279s Reading package lists... 280s Building dependency tree... 280s Reading state information... 280s rng-tools-debian is already the newest version (2.4). 280s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 280s Reading package lists... 280s Building dependency tree... 280s Reading state information... 280s The following packages will be REMOVED: 280s cloud-init* python3-configobj* python3-debconf* 280s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 280s After this operation, 3256 kB disk space will be freed. 280s (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 ... 52180 files and directories currently installed.) 280s Removing cloud-init (24.1.2-0ubuntu1) ... 281s Removing python3-configobj (5.0.8-3) ... 281s Removing python3-debconf (1.5.86) ... 281s Processing triggers for man-db (2.12.0-3) ... 285s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 285s (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 ... 51791 files and directories currently installed.) 285s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 285s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 285s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 285s invoke-rc.d: policy-rc.d denied execution of try-restart. 285s Reading package lists... 285s Building dependency tree... 285s Reading state information... 285s linux-generic is already the newest version (6.8.0-11.11+1). 285s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 285s Reading package lists... 285s Building dependency tree... 285s Reading state information... 285s Calculating upgrade... 285s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 285s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 285s Reading package lists... 285s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 285s Reading package lists... 285s Building dependency tree... 285s Reading state information... 286s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 286s autopkgtest [21:34:40]: rebooting testbed after setup commands that affected boot 315s Reading package lists... 318s Building dependency tree... 318s Reading state information... 318s Starting pkgProblemResolver with broken count: 0 318s Starting 2 pkgProblemResolver with broken count: 0 318s Done 318s The following additional packages will be installed: 318s autofs autofs-hesiod autofs-ldap keyutils libhesiod0 libidn12 libnfsidmap1 318s nfs-common nfs-kernel-server rpcbind 318s Suggested packages: 318s open-iscsi watchdog 318s The following NEW packages will be installed: 318s autofs autofs-hesiod autofs-ldap autopkgtest-satdep keyutils libhesiod0 318s libidn12 libnfsidmap1 nfs-common nfs-kernel-server rpcbind 318s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 318s Need to get 988 kB/989 kB of archives. 318s After this operation, 3360 kB of additional disk space will be used. 318s Get:1 /tmp/autopkgtest.us1X95/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 318s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libnfsidmap1 s390x 1:2.6.3-3ubuntu1 [49.0 kB] 318s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x rpcbind s390x 1.2.6-7build1 [47.6 kB] 318s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x keyutils s390x 1.6.3-3 [57.7 kB] 318s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x nfs-common s390x 1:2.6.3-3ubuntu1 [250 kB] 318s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x autofs s390x 5.1.9-1ubuntu1 [293 kB] 318s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x libidn12 s390x 1.42-1 [59.2 kB] 318s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x nfs-kernel-server s390x 1:2.6.3-3ubuntu1 [155 kB] 318s Get:9 http://ftpmaster.internal/ubuntu noble/universe s390x libhesiod0 s390x 3.2.1-4 [22.3 kB] 318s Get:10 http://ftpmaster.internal/ubuntu noble/universe s390x autofs-hesiod s390x 5.1.9-1ubuntu1 [10.6 kB] 318s Get:11 http://ftpmaster.internal/ubuntu noble/universe s390x autofs-ldap s390x 5.1.9-1ubuntu1 [44.3 kB] 318s Preconfiguring packages ... 318s Fetched 988 kB in 1s (1072 kB/s) 318s Selecting previously unselected package libnfsidmap1:s390x. 318s (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 ... 51736 files and directories currently installed.) 318s Preparing to unpack .../00-libnfsidmap1_1%3a2.6.3-3ubuntu1_s390x.deb ... 318s Unpacking libnfsidmap1:s390x (1:2.6.3-3ubuntu1) ... 318s Selecting previously unselected package rpcbind. 318s Preparing to unpack .../01-rpcbind_1.2.6-7build1_s390x.deb ... 318s Unpacking rpcbind (1.2.6-7build1) ... 318s Selecting previously unselected package keyutils. 318s Preparing to unpack .../02-keyutils_1.6.3-3_s390x.deb ... 318s Unpacking keyutils (1.6.3-3) ... 318s Selecting previously unselected package nfs-common. 318s Preparing to unpack .../03-nfs-common_1%3a2.6.3-3ubuntu1_s390x.deb ... 318s Unpacking nfs-common (1:2.6.3-3ubuntu1) ... 318s Selecting previously unselected package autofs. 318s Preparing to unpack .../04-autofs_5.1.9-1ubuntu1_s390x.deb ... 318s Unpacking autofs (5.1.9-1ubuntu1) ... 318s Selecting previously unselected package libidn12:s390x. 318s Preparing to unpack .../05-libidn12_1.42-1_s390x.deb ... 318s Unpacking libidn12:s390x (1.42-1) ... 318s Selecting previously unselected package nfs-kernel-server. 318s Preparing to unpack .../06-nfs-kernel-server_1%3a2.6.3-3ubuntu1_s390x.deb ... 318s Unpacking nfs-kernel-server (1:2.6.3-3ubuntu1) ... 318s Selecting previously unselected package libhesiod0:s390x. 318s Preparing to unpack .../07-libhesiod0_3.2.1-4_s390x.deb ... 318s Unpacking libhesiod0:s390x (3.2.1-4) ... 318s Selecting previously unselected package autofs-hesiod. 318s Preparing to unpack .../08-autofs-hesiod_5.1.9-1ubuntu1_s390x.deb ... 318s Unpacking autofs-hesiod (5.1.9-1ubuntu1) ... 318s Selecting previously unselected package autofs-ldap. 318s Preparing to unpack .../09-autofs-ldap_5.1.9-1ubuntu1_s390x.deb ... 318s Unpacking autofs-ldap (5.1.9-1ubuntu1) ... 318s Selecting previously unselected package autopkgtest-satdep. 318s Preparing to unpack .../10-2-autopkgtest-satdep.deb ... 318s Unpacking autopkgtest-satdep (0) ... 318s Setting up libnfsidmap1:s390x (1:2.6.3-3ubuntu1) ... 318s Setting up rpcbind (1.2.6-7build1) ... 318s Created symlink /etc/systemd/system/multi-user.target.wants/rpcbind.service → /usr/lib/systemd/system/rpcbind.service. 318s Created symlink /etc/systemd/system/sockets.target.wants/rpcbind.socket → /usr/lib/systemd/system/rpcbind.socket. 318s Setting up libidn12:s390x (1.42-1) ... 318s Setting up keyutils (1.6.3-3) ... 318s Setting up autofs (5.1.9-1ubuntu1) ... 318s 318s Creating config file /etc/auto.master with new version 319s 319s Creating config file /etc/auto.net with new version 319s 319s Creating config file /etc/auto.misc with new version 319s 319s Creating config file /etc/auto.smb with new version 319s 319s Creating config file /etc/autofs.conf with new version 319s 319s Creating config file /etc/default/autofs with new version 319s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 320s Created symlink /etc/systemd/system/multi-user.target.wants/autofs.service → /usr/lib/systemd/system/autofs.service. 320s Setting up libhesiod0:s390x (3.2.1-4) ... 320s Setting up nfs-common (1:2.6.3-3ubuntu1) ... 320s 320s Creating config file /etc/idmapd.conf with new version 321s 321s Creating config file /etc/nfs.conf with new version 321s info: Selecting UID from range 100 to 999 ... 321s 321s info: Adding system user `statd' (UID 108) ... 321s info: Adding new user `statd' (UID 108) with group `nogroup' ... 321s info: Not creating home directory `/var/lib/nfs'. 321s Created symlink /etc/systemd/system/multi-user.target.wants/nfs-client.target → /usr/lib/systemd/system/nfs-client.target. 321s Created symlink /etc/systemd/system/remote-fs.target.wants/nfs-client.target → /usr/lib/systemd/system/nfs-client.target. 321s auth-rpcgss-module.service is a disabled or a static unit, not starting it. 321s nfs-idmapd.service is a disabled or a static unit, not starting it. 321s nfs-utils.service is a disabled or a static unit, not starting it. 321s proc-fs-nfsd.mount is a disabled or a static unit, not starting it. 321s rpc-gssd.service is a disabled or a static unit, not starting it. 321s rpc-statd-notify.service is a disabled or a static unit, not starting it. 321s rpc-statd.service is a disabled or a static unit, not starting it. 321s rpc-svcgssd.service is a disabled or a static unit, not starting it. 322s Setting up autofs-ldap (5.1.9-1ubuntu1) ... 322s Setting up nfs-kernel-server (1:2.6.3-3ubuntu1) ... 322s Created symlink /etc/systemd/system/nfs-mountd.service.requires/fsidd.service → /usr/lib/systemd/system/fsidd.service. 322s Created symlink /etc/systemd/system/nfs-server.service.requires/fsidd.service → /usr/lib/systemd/system/fsidd.service. 322s Created symlink /etc/systemd/system/nfs-client.target.wants/nfs-blkmap.service → /usr/lib/systemd/system/nfs-blkmap.service. 322s Created symlink /etc/systemd/system/multi-user.target.wants/nfs-server.service → /usr/lib/systemd/system/nfs-server.service. 322s nfs-mountd.service is a disabled or a static unit, not starting it. 322s nfsdcld.service is a disabled or a static unit, not starting it. 323s 323s Creating config file /etc/exports with new version 323s 323s Creating config file /etc/default/nfs-kernel-server with new version 324s Setting up autofs-hesiod (5.1.9-1ubuntu1) ... 324s Setting up autopkgtest-satdep (0) ... 324s Processing triggers for man-db (2.12.0-3) ... 324s Processing triggers for libc-bin (2.39-0ubuntu6) ... 326s (Reading database ... 52008 files and directories currently installed.) 326s Removing autopkgtest-satdep (0) ... 328s autopkgtest [21:35:22]: test nfs-mount: [----------------------- 329s + set -e 329s + date --utc 329s + now=Sat Mar 23 21:35:21 UTC 2024 329s + result=0 329s + echo creating /nfspub nfs export 329s + mkdir -p /nfspub 329s + echo Sat Mar 23 21:35:21 UTC 2024 - This is an nfs public export 329s + echo /nfspub *(ro,no_subtree_check) 329s + exportfs -avr 329s creating /nfspub nfs export 329s exporting *:/nfspub 329s Configuring automount for nfs on /net 329s + echo Configuring automount for nfs on /net 329s + mkdir -p /etc/auto.master.d 329s + echo /net -hosts -intr,soft --timeout=180 329s + systemctl restart autofs 329s Accessing the autofs mountpoint 329s + echo Accessing the autofs mountpoint 329s + timeout 30s grep -qE Sat Mar 23 21:35:21 UTC 2024.*nfs public /net/localhost/nfspub/nfs-public.txt 329s + [ 0 -ne 0 ] 329s autopkgtest [21:35:23]: test nfs-mount: -----------------------] 329s nfs-mount PASS 329s autopkgtest [21:35:23]: test nfs-mount: - - - - - - - - - - results - - - - - - - - - - 330s autopkgtest [21:35:24]: test ldap-map-sasl-auth: preparing testbed 333s Reading package lists... 333s Building dependency tree... 333s Reading state information... 334s Starting pkgProblemResolver with broken count: 0 334s Starting 2 pkgProblemResolver with broken count: 0 334s Done 335s The following additional packages will be installed: 335s db-util db5.3-util krb5-admin-server krb5-config krb5-kdc krb5-user 335s ldap-utils libevent-2.1-7 libgssrpc4 libkadm5clnt-mit12 libkadm5srv-mit12 335s libkdb5-10 libltdl7 libodbc2 libsasl2-modules-gssapi-mit libverto-libevent1 335s libverto1 sasl2-bin schema2ldif slapd 335s Suggested packages: 335s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc odbc-postgresql tdsodbc 335s The following NEW packages will be installed: 335s autopkgtest-satdep db-util db5.3-util krb5-admin-server krb5-config krb5-kdc 335s krb5-user ldap-utils libevent-2.1-7 libgssrpc4 libkadm5clnt-mit12 335s libkadm5srv-mit12 libkdb5-10 libltdl7 libodbc2 libsasl2-modules-gssapi-mit 335s libverto-libevent1 libverto1 sasl2-bin schema2ldif slapd 335s 0 upgraded, 21 newly installed, 0 to remove and 0 not upgraded. 335s Need to get 2981 kB/2982 kB of archives. 335s After this operation, 9843 kB of additional disk space will be used. 335s Get:1 /tmp/autopkgtest.us1X95/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [804 B] 335s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x krb5-config all 2.7 [22.0 kB] 335s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libgssrpc4 s390x 1.20.1-5build1 [58.9 kB] 335s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5clnt-mit12 s390x 1.20.1-5build1 [40.5 kB] 335s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libkdb5-10 s390x 1.20.1-5build1 [41.4 kB] 335s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5srv-mit12 s390x 1.20.1-5build1 [55.2 kB] 335s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-user s390x 1.20.1-5build1 [110 kB] 335s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x libevent-2.1-7 s390x 2.1.12-stable-9 [144 kB] 335s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x libverto1 s390x 0.3.1-1ubuntu5 [10.6 kB] 335s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x libverto-libevent1 s390x 0.3.1-1ubuntu5 [5810 B] 335s Get:11 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-kdc s390x 1.20.1-5build1 [188 kB] 335s Get:12 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-admin-server s390x 1.20.1-5build1 [95.9 kB] 335s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x db5.3-util s390x 5.3.28+dfsg2-4 [75.4 kB] 335s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x db-util all 1:5.3.21ubuntu1 [2416 B] 335s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x sasl2-bin s390x 2.1.28+dfsg1-4 [97.1 kB] 335s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x libltdl7 s390x 2.4.7-7 [41.6 kB] 335s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x libodbc2 s390x 2.3.12-1 [164 kB] 335s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x slapd s390x 2.6.7+dfsg-1~exp1ubuntu1 [1617 kB] 336s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x ldap-utils s390x 2.6.7+dfsg-1~exp1ubuntu1 [165 kB] 336s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x libsasl2-modules-gssapi-mit s390x 2.1.28+dfsg1-4 [31.5 kB] 336s Get:21 http://ftpmaster.internal/ubuntu noble/universe s390x schema2ldif all 1.3-4 [15.1 kB] 336s Preconfiguring packages ... 336s Fetched 2981 kB in 2s (1674 kB/s) 336s Selecting previously unselected package krb5-config. 336s (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 ... 52008 files and directories currently installed.) 336s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 336s Unpacking krb5-config (2.7) ... 336s Selecting previously unselected package libgssrpc4:s390x. 336s Preparing to unpack .../01-libgssrpc4_1.20.1-5build1_s390x.deb ... 336s Unpacking libgssrpc4:s390x (1.20.1-5build1) ... 336s Selecting previously unselected package libkadm5clnt-mit12:s390x. 336s Preparing to unpack .../02-libkadm5clnt-mit12_1.20.1-5build1_s390x.deb ... 336s Unpacking libkadm5clnt-mit12:s390x (1.20.1-5build1) ... 336s Selecting previously unselected package libkdb5-10:s390x. 336s Preparing to unpack .../03-libkdb5-10_1.20.1-5build1_s390x.deb ... 336s Unpacking libkdb5-10:s390x (1.20.1-5build1) ... 336s Selecting previously unselected package libkadm5srv-mit12:s390x. 336s Preparing to unpack .../04-libkadm5srv-mit12_1.20.1-5build1_s390x.deb ... 336s Unpacking libkadm5srv-mit12:s390x (1.20.1-5build1) ... 336s Selecting previously unselected package krb5-user. 336s Preparing to unpack .../05-krb5-user_1.20.1-5build1_s390x.deb ... 336s Unpacking krb5-user (1.20.1-5build1) ... 336s Selecting previously unselected package libevent-2.1-7:s390x. 336s Preparing to unpack .../06-libevent-2.1-7_2.1.12-stable-9_s390x.deb ... 336s Unpacking libevent-2.1-7:s390x (2.1.12-stable-9) ... 336s Selecting previously unselected package libverto1:s390x. 336s Preparing to unpack .../07-libverto1_0.3.1-1ubuntu5_s390x.deb ... 336s Unpacking libverto1:s390x (0.3.1-1ubuntu5) ... 336s Selecting previously unselected package libverto-libevent1:s390x. 336s Preparing to unpack .../08-libverto-libevent1_0.3.1-1ubuntu5_s390x.deb ... 336s Unpacking libverto-libevent1:s390x (0.3.1-1ubuntu5) ... 336s Selecting previously unselected package krb5-kdc. 336s Preparing to unpack .../09-krb5-kdc_1.20.1-5build1_s390x.deb ... 336s Unpacking krb5-kdc (1.20.1-5build1) ... 337s Selecting previously unselected package krb5-admin-server. 337s Preparing to unpack .../10-krb5-admin-server_1.20.1-5build1_s390x.deb ... 337s Unpacking krb5-admin-server (1.20.1-5build1) ... 337s Selecting previously unselected package db5.3-util. 337s Preparing to unpack .../11-db5.3-util_5.3.28+dfsg2-4_s390x.deb ... 337s Unpacking db5.3-util (5.3.28+dfsg2-4) ... 337s Selecting previously unselected package db-util. 337s Preparing to unpack .../12-db-util_1%3a5.3.21ubuntu1_all.deb ... 337s Unpacking db-util (1:5.3.21ubuntu1) ... 337s Selecting previously unselected package sasl2-bin. 337s Preparing to unpack .../13-sasl2-bin_2.1.28+dfsg1-4_s390x.deb ... 337s Unpacking sasl2-bin (2.1.28+dfsg1-4) ... 337s Selecting previously unselected package libltdl7:s390x. 337s Preparing to unpack .../14-libltdl7_2.4.7-7_s390x.deb ... 337s Unpacking libltdl7:s390x (2.4.7-7) ... 337s Selecting previously unselected package libodbc2:s390x. 337s Preparing to unpack .../15-libodbc2_2.3.12-1_s390x.deb ... 337s Unpacking libodbc2:s390x (2.3.12-1) ... 337s Selecting previously unselected package slapd. 337s Preparing to unpack .../16-slapd_2.6.7+dfsg-1~exp1ubuntu1_s390x.deb ... 337s Unpacking slapd (2.6.7+dfsg-1~exp1ubuntu1) ... 337s Selecting previously unselected package ldap-utils. 337s Preparing to unpack .../17-ldap-utils_2.6.7+dfsg-1~exp1ubuntu1_s390x.deb ... 337s Unpacking ldap-utils (2.6.7+dfsg-1~exp1ubuntu1) ... 337s Selecting previously unselected package libsasl2-modules-gssapi-mit:s390x. 337s Preparing to unpack .../18-libsasl2-modules-gssapi-mit_2.1.28+dfsg1-4_s390x.deb ... 337s Unpacking libsasl2-modules-gssapi-mit:s390x (2.1.28+dfsg1-4) ... 337s Selecting previously unselected package schema2ldif. 337s Preparing to unpack .../19-schema2ldif_1.3-4_all.deb ... 337s Unpacking schema2ldif (1.3-4) ... 337s Selecting previously unselected package autopkgtest-satdep. 337s Preparing to unpack .../20-3-autopkgtest-satdep.deb ... 337s Unpacking autopkgtest-satdep (0) ... 337s Setting up schema2ldif (1.3-4) ... 337s Setting up ldap-utils (2.6.7+dfsg-1~exp1ubuntu1) ... 337s Setting up libgssrpc4:s390x (1.20.1-5build1) ... 337s Setting up krb5-config (2.7) ... 337s Setting up db5.3-util (5.3.28+dfsg2-4) ... 337s Setting up libevent-2.1-7:s390x (2.1.12-stable-9) ... 337s Setting up libltdl7:s390x (2.4.7-7) ... 337s Setting up libodbc2:s390x (2.3.12-1) ... 337s Setting up libsasl2-modules-gssapi-mit:s390x (2.1.28+dfsg1-4) ... 337s Setting up libkadm5clnt-mit12:s390x (1.20.1-5build1) ... 337s Setting up slapd (2.6.7+dfsg-1~exp1ubuntu1) ... 337s Creating new user openldap... done. 337s Creating initial configuration... done. 337s Creating LDAP directory... done. 338s Setting up libkdb5-10:s390x (1.20.1-5build1) ... 338s Setting up db-util (1:5.3.21ubuntu1) ... 338s Setting up sasl2-bin (2.1.28+dfsg1-4) ... 342s saslauthd.service is a disabled or a static unit, not starting it. 342s Setting up libkadm5srv-mit12:s390x (1.20.1-5build1) ... 342s Setting up krb5-user (1.20.1-5build1) ... 342s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 342s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 342s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 342s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 342s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 342s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 342s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 342s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 342s Setting up libverto-libevent1:s390x (0.3.1-1ubuntu5) ... 342s Setting up libverto1:s390x (0.3.1-1ubuntu5) ... 342s Setting up krb5-kdc (1.20.1-5build1) ... 342s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service. 342s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 342s Setting up krb5-admin-server (1.20.1-5build1) ... 342s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service. 342s Setting up autopkgtest-satdep (0) ... 342s Processing triggers for libc-bin (2.39-0ubuntu6) ... 342s Processing triggers for ufw (0.36.2-5) ... 342s Processing triggers for man-db (2.12.0-3) ... 344s (Reading database ... 52525 files and directories currently installed.) 344s Removing autopkgtest-satdep (0) ... 344s autopkgtest [21:35:38]: test ldap-map-sasl-auth: [----------------------- 345s ## Setting up Kerberos 345s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 345s master key name 'K/M@EXAMPLE.FAKE' 345s No policy specified for user3753@EXAMPLE.FAKE; defaulting to no policy 345s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 345s Principal "user3753@EXAMPLE.FAKE" created. 345s No policy specified for ldap/server.example.fake@EXAMPLE.FAKE; defaulting to no policy 345s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 345s Principal "ldap/server.example.fake@EXAMPLE.FAKE" created. 345s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 345s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 345s Entry for principal ldap/server.example.fake with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab FILE:/etc/krb5.keytab. 345s 345s ## Setting up slapd 345s Backing up /etc/ldap/slapd.d in /var/backups/slapd-2.6.7+dfsg-1~exp1ubuntu1... done. 345s Moving old database directory to /var/backups: 345s - directory unknown... done. 345s Creating initial configuration... done. 345s Creating LDAP directory... done. 346s Waiting for slapd to be ready . 346s 346s ## Configuring slapd 346s SASL/EXTERNAL authentication started 346s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 346s SASL SSF: 0 346s modifying entry "cn=config" 346s 346s modifying entry "olcDatabase={1}mdb,cn=config" 346s 346s 346s ## Adding autofs schema to ldap 346s 346s SASL/EXTERNAL authentication started 346s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 346s SASL SSF: 0 346s executing 'ldapadd -Y EXTERNAL -H ldapi:/// -f /etc/ldap/schema/autofs.ldif' 346s SASL/EXTERNAL authentication started 346s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 346s SASL SSF: 0 346s adding new entry "cn=autofs,cn=schema,cn=config" 346s 346s 346s ## Adding automount maps to ldap 346s adding new entry "ou=auto.indirect,dc=example,dc=fake" 346s 346s adding new entry "cn=/,ou=auto.indirect,dc=example,dc=fake" 346s 346s 346s ## Populating NFS export 346s exporting *:/storage 346s 346s ## Creating test user user3753 in sasldb 346s 346s ## Testing shared secret mechanism auth one by one before letting autofs try it 346s 346s Testing mechanism DIGEST-MD5 346s SASL/DIGEST-MD5 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 128 346s SASL data security layer installed. 346s dn:uid=user3753@example.fake,cn=digest-md5,cn=auth 346s 346s Testing mechanism SCRAM-SHA-1 346s SASL/SCRAM-SHA-1 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=scram-sha-1,cn=auth 346s 346s Testing mechanism SCRAM-SHA-224 346s SASL/SCRAM-SHA-224 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=scram-sha-224,cn=auth 346s 346s Testing mechanism SCRAM-SHA-256 346s SASL/SCRAM-SHA-256 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=scram-sha-256,cn=auth 346s 346s Testing mechanism SCRAM-SHA-384 346s SASL/SCRAM-SHA-384 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=scram-sha-384,cn=auth 346s 346s Testing mechanism SCRAM-SHA-512 346s SASL/SCRAM-SHA-512 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=scram-sha-512,cn=auth 346s 346s Testing mechanism NTLM 346s SASL/NTLM authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=ntlm,cn=auth 346s 346s Testing mechanism CRAM-MD5 346s SASL/CRAM-MD5 authentication started 346s SASL username: user3753@example.fake 346s SASL SSF: 0 346s dn:uid=user3753@example.fake,cn=cram-md5,cn=auth 346s 346s ## Testing GSSAPI mechanisms before letting autofs try it 346s 346s Password for user3753@EXAMPLE.FAKE: 346s Testing mechanism GSSAPI 346s SASL/GSSAPI authentication started 346s SASL username: user3753@EXAMPLE.FAKE 346s SASL SSF: 256 346s SASL data security layer installed. 346s dn:uid=user3753,cn=gssapi,cn=auth 346s 346s Testing mechanism GSS-SPNEGO 346s SASL/GSS-SPNEGO authentication started 346s SASL username: user3753@EXAMPLE.FAKE 346s SASL SSF: 256 346s SASL data security layer installed. 346s dn:uid=user3753,cn=gss-spnego,cn=auth 346s 346s ## Adding automount to nsswitch.conf 346s 346s ## Setting up autofs 346s 346s ## Testing autofs with SASL shared secret mechanisms 346s 346s ## Configuring autofs to use mechanism DIGEST-MD5 347s 347s ## Confirming target is not mounted 347s total 4 347s drwxr-xr-x 2 root root 0 Mar 23 21:35 . 347s drwxr-xr-x 23 root root 4096 Mar 23 21:35 .. 347s 347s ## Triggering a mount, and checking that the mountpoint has the test file 347s ls: cannot access '/mnt/storage/test_file_3753': No such file or directory 347s ## Something failed, gathering logs 347s 347s ## syslog: 347s 2024-03-23T21:35:21.603752+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (utomount)[2739]: autofs.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS 347s 2024-03-23T21:35:21.603863+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting autofs.service - Automounts filesystems on demand... 347s 2024-03-23T21:35:21.613039+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: tmp-auto1qph0l.mount: Deactivated successfully. 347s 2024-03-23T21:35:21.622890+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started autofs.service - Automounts filesystems on demand. 347s 2024-03-23T21:35:22.605467+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: tmp-autouwTSg3.mount: Deactivated successfully. 347s 2024-03-23T21:35:23.502698+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: systemd-hostnamed.service: Deactivated successfully. 347s 2024-03-23T21:35:30.210771+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose addgroup[3165]: Selecting GID from range 100 to 999 ... 347s 2024-03-23T21:35:30.212856+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose addgroup[3165]: Adding group `openldap' (GID 110) ... 347s 2024-03-23T21:35:30.259427+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose adduser[3175]: Selecting UID from range 100 to 999 ... 347s 2024-03-23T21:35:30.261342+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose adduser[3175]: Adding system user `openldap' (UID 109) ... 347s 2024-03-23T21:35:30.262856+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose adduser[3175]: Adding new user `openldap' (UID 109) with group `openldap' ... 347s 2024-03-23T21:35:30.275299+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose adduser[3175]: Creating home directory `/var/lib/ldap' ... 347s 2024-03-23T21:35:30.379226+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: kauditd_printk_skb: 78 callbacks suppressed 347s 2024-03-23T21:35:30.379266+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: audit: type=1400 audit(1711229730.368:89): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/slapd" pid=3236 comm="apparmor_parser" 347s 2024-03-23T21:35:30.387673+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3244 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:30.387770+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:30.631863+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 243 ms. 347s 2024-03-23T21:35:30.725482+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 347s 2024-03-23T21:35:30.730318+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3289]: * Starting OpenLDAP slapd 347s 2024-03-23T21:35:30.736138+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3295]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu1 (Feb 6 2024 19:46:16) $#012#011Ubuntu Developers 347s 2024-03-23T21:35:30.739288+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: audit: type=1400 audit(1711229730.728:90): apparmor="DENIED" operation="open" class="file" profile="/usr/sbin/slapd" name="/etc/gnutls/config" pid=3295 comm="slapd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0 347s 2024-03-23T21:35:30.742799+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3296]: slapd starting 347s 2024-03-23T21:35:30.743928+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3289]: ...done. 347s 2024-03-23T21:35:30.749397+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 347s 2024-03-23T21:35:30.872726+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3320 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:30.872828+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:31.113097+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 239 ms. 347s 2024-03-23T21:35:31.229230+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3363 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:31.229318+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:31.469093+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 240 ms. 347s 2024-03-23T21:35:31.645902+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3419 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:31.646015+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:31.884001+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 237 ms. 347s 2024-03-23T21:35:32.044368+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3464 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:32.044469+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:32.330647+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 285 ms. 347s 2024-03-23T21:35:32.439412+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 347s 2024-03-23T21:35:32.442563+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (krb5kdc)[3505]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 347s 2024-03-23T21:35:32.445131+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose krb5kdc[3505]: krb5kdc: cannot initialize realm NOVALOCAL - see log file for details 347s 2024-03-23T21:35:32.459482+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: krb5-kdc.service: Control process exited, code=exited, status=1/FAILURE 347s 2024-03-23T21:35:32.459578+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: krb5-kdc.service: Failed with result 'exit-code'. 347s 2024-03-23T21:35:32.459635+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Failed to start krb5-kdc.service - Kerberos 5 Key Distribution Center. 347s 2024-03-23T21:35:32.552886+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3512 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:32.553002+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:32.800697+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 247 ms. 347s 2024-03-23T21:35:32.967190+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3557 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:32.967294+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:33.229292+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 261 ms. 347s 2024-03-23T21:35:33.339446+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started krb5-admin-server.service - Kerberos 5 Admin Server. 347s 2024-03-23T21:35:33.349385+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (kadmind)[3598]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 347s 2024-03-23T21:35:33.353401+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kadmind[3598]: kadmind: Cannot open DB2 database '/var/lib/krb5kdc/principal': No such file or directory while initializing, aborting 347s 2024-03-23T21:35:33.360389+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: krb5-admin-server.service: Main process exited, code=exited, status=1/FAILURE 347s 2024-03-23T21:35:33.360462+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: krb5-admin-server.service: Failed with result 'exit-code'. 347s 2024-03-23T21:35:33.608773+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd-timesyncd[368]: Timed out waiting for reply from 185.125.190.58:123 (ntp.ubuntu.com). 347s 2024-03-23T21:35:35.211863+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd-timesyncd[368]: Contacted time server 185.125.190.56:123 (ntp.ubuntu.com). 347s 2024-03-23T21:35:35.212072+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd-resolved[352]: Clock change detected. Flushing caches. 347s 2024-03-23T21:35:35.217391+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd-timesyncd[368]: Initial clock synchronization to Sat 2024-03-23 21:35:35.210408 UTC. 347s 2024-03-23T21:35:38.966486+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3756 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:38.967619+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:39.226918+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 260 ms. 347s 2024-03-23T21:35:39.253726+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd-resolved[352]: System hostname changed to 'server.example.fake'. 347s 2024-03-23T21:35:39.309833+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started krb5-admin-server.service - Kerberos 5 Admin Server. 347s 2024-03-23T21:35:39.317766+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 347s 2024-03-23T21:35:39.361522+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (kadmind)[3801]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 347s 2024-03-23T21:35:39.361594+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kadmind[3801]: kadmind: starting... 347s 2024-03-23T21:35:39.363430+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (krb5kdc)[3802]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 347s 2024-03-23T21:35:39.373731+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center. 347s 2024-03-23T21:35:39.689469+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopping slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 347s 2024-03-23T21:35:39.698405+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3836]: * Stopping OpenLDAP slapd 347s 2024-03-23T21:35:39.699074+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3296]: daemon: shutdown requested and initiated. 347s 2024-03-23T21:35:39.699324+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3296]: slapd shutdown: waiting for 0 operations/tasks to finish 347s 2024-03-23T21:35:39.700523+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3296]: slapd stopped. 347s 2024-03-23T21:35:39.719734+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3836]: ...done. 347s 2024-03-23T21:35:39.719822+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: slapd.service: Deactivated successfully. 347s 2024-03-23T21:35:39.719969+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopped slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 347s 2024-03-23T21:35:39.887300+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: audit: type=1400 audit(1711229739.870:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="/usr/sbin/slapd" pid=3914 comm="apparmor_parser" 347s 2024-03-23T21:35:39.906927+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading requested from client PID 3924 ('systemctl') (unit session-1.scope)... 347s 2024-03-23T21:35:39.907596+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading... 347s 2024-03-23T21:35:40.193649+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Reloading finished in 285 ms. 347s 2024-03-23T21:35:40.281268+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 347s 2024-03-23T21:35:40.289086+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3970]: * Starting OpenLDAP slapd 347s 2024-03-23T21:35:40.294983+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3976]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu1 (Feb 6 2024 19:46:16) $#012#011Ubuntu Developers 347s 2024-03-23T21:35:40.297310+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: audit: type=1400 audit(1711229740.280:92): apparmor="DENIED" operation="open" class="file" profile="/usr/sbin/slapd" name="/etc/gnutls/config" pid=3976 comm="slapd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0 347s 2024-03-23T21:35:40.301928+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3977]: slapd starting 347s 2024-03-23T21:35:40.302594+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3970]: ...done. 347s 2024-03-23T21:35:40.304161+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 347s 2024-03-23T21:35:40.370495+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopping slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 347s 2024-03-23T21:35:40.379425+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3983]: * Stopping OpenLDAP slapd 347s 2024-03-23T21:35:40.379946+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3977]: daemon: shutdown requested and initiated. 347s 2024-03-23T21:35:40.380107+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3977]: slapd shutdown: waiting for 0 operations/tasks to finish 347s 2024-03-23T21:35:40.381089+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3977]: slapd stopped. 347s 2024-03-23T21:35:40.400403+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3983]: ...done. 347s 2024-03-23T21:35:40.400848+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: slapd.service: Deactivated successfully. 347s 2024-03-23T21:35:40.400933+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopped slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 347s 2024-03-23T21:35:40.411319+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)... 347s 2024-03-23T21:35:40.416018+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3990]: * Starting OpenLDAP slapd 347s 2024-03-23T21:35:40.421091+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3996]: @(#) $OpenLDAP: slapd 2.6.7+dfsg-1~exp1ubuntu1 (Feb 6 2024 19:46:16) $#012#011Ubuntu Developers 347s 2024-03-23T21:35:40.427620+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: audit: type=1400 audit(1711229740.410:93): apparmor="DENIED" operation="open" class="file" profile="/usr/sbin/slapd" name="/etc/gnutls/config" pid=3996 comm="slapd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0 347s 2024-03-23T21:35:40.428094+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: slapd starting 347s 2024-03-23T21:35:40.435118+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3990]: ...done. 347s 2024-03-23T21:35:40.435177+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started slapd.service - LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol). 347s 2024-03-23T21:35:40.439909+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 op=1 RESULT tag=103 err=0 qtime=0.000003 etime=0.000208 text= 347s 2024-03-23T21:35:40.440231+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 op=2 MOD dn="olcDatabase={1}mdb,cn=config" 347s 2024-03-23T21:35:40.440291+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 op=2 MOD attr=olcAccess 347s 2024-03-23T21:35:40.440338+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 op=2 RESULT tag=103 err=0 qtime=0.000008 etime=0.000175 text= 347s 2024-03-23T21:35:40.440721+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 op=3 UNBIND 347s 2024-03-23T21:35:40.440774+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1001 fd=12 closed 347s 2024-03-23T21:35:40.450851+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 347s 2024-03-23T21:35:40.451252+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.451305+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: 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" 347s 2024-03-23T21:35:40.451352+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 347s 2024-03-23T21:35:40.451469+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: connection_input: conn=1002 deferring operation: binding 347s 2024-03-23T21:35:40.452217+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=0 RESULT tag=97 err=0 qtime=0.000003 etime=0.000563 text= 347s 2024-03-23T21:35:40.452278+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=1 SRCH base="cn=config" scope=2 deref=0 filter="(cn=config)" 347s 2024-03-23T21:35:40.452377+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=1 SRCH attr=dn 347s 2024-03-23T21:35:40.452439+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000076 etime=0.000125 nentries=1 text= 347s 2024-03-23T21:35:40.452497+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 op=2 UNBIND 347s 2024-03-23T21:35:40.452542+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1002 fd=12 closed 347s 2024-03-23T21:35:40.503632+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 347s 2024-03-23T21:35:40.503986+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.504041+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: 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" 347s 2024-03-23T21:35:40.504421+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 347s 2024-03-23T21:35:40.504506+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=0 RESULT tag=97 err=0 qtime=0.000007 etime=0.000151 text= 347s 2024-03-23T21:35:40.504556+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=1 SRCH base="cn=schema,cn=config" scope=2 deref=0 filter="(cn={*}autofs)" 347s 2024-03-23T21:35:40.504604+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=1 SRCH attr=cn 347s 2024-03-23T21:35:40.504961+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000003 etime=0.000379 nentries=0 text= 347s 2024-03-23T21:35:40.505008+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 op=2 UNBIND 347s 2024-03-23T21:35:40.505051+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1003 fd=12 closed 347s 2024-03-23T21:35:40.508441+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 fd=12 ACCEPT from PATH=/var/run/slapd/ldapi (PATH=/var/run/slapd/ldapi) 347s 2024-03-23T21:35:40.508745+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.508801+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: 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" 347s 2024-03-23T21:35:40.508870+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=0 BIND dn="gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth" mech=EXTERNAL bind_ssf=0 ssf=71 347s 2024-03-23T21:35:40.508941+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: connection_input: conn=1004 deferring operation: binding 347s 2024-03-23T21:35:40.509150+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=0 RESULT tag=97 err=0 qtime=0.000013 etime=0.000406 text= 347s 2024-03-23T21:35:40.509206+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=1 ADD dn="cn=autofs,cn=schema,cn=config" 347s 2024-03-23T21:35:40.509584+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=2 UNBIND 347s 2024-03-23T21:35:40.509650+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 op=1 RESULT tag=105 err=0 qtime=0.000042 etime=0.000715 text= 347s 2024-03-23T21:35:40.509712+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1004 fd=12 closed 347s 2024-03-23T21:35:40.514191+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 fd=12 ACCEPT from IP=[::1]:49096 (IP=[::]:389) 347s 2024-03-23T21:35:40.514345+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=0 BIND dn="cn=admin,dc=example,dc=fake" method=128 347s 2024-03-23T21:35:40.514397+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=0 BIND dn="cn=admin,dc=example,dc=fake" mech=SIMPLE bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.514447+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=0 RESULT tag=97 err=0 qtime=0.000003 etime=0.000035 text= 347s 2024-03-23T21:35:40.514677+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=1 ADD dn="ou=auto.indirect,dc=example,dc=fake" 347s 2024-03-23T21:35:40.516010+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=1 RESULT tag=105 err=0 qtime=0.000003 etime=0.001359 text= 347s 2024-03-23T21:35:40.516427+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=2 ADD dn="cn=/,ou=auto.indirect,dc=example,dc=fake" 347s 2024-03-23T21:35:40.517443+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=2 RESULT tag=105 err=0 qtime=0.000010 etime=0.000961 text= 347s 2024-03-23T21:35:40.517508+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 op=3 UNBIND 347s 2024-03-23T21:35:40.517551+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1005 fd=12 closed 347s 2024-03-23T21:35:40.534703+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 fd=12 ACCEPT from IP=[::1]:49104 (IP=[::]:389) 347s 2024-03-23T21:35:40.534903+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.534951+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=0 RESULT tag=97 err=14 qtime=0.000007 etime=0.000037 text=SASL(0): successful result: 347s 2024-03-23T21:35:40.540361+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.540423+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.540503+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=1 BIND dn="uid=user3753@example.fake,cn=digest-md5,cn=auth" mech=DIGEST-MD5 bind_ssf=128 ssf=128 347s 2024-03-23T21:35:40.541609+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=1 RESULT tag=97 err=0 qtime=0.000004 etime=0.002564 text= 347s 2024-03-23T21:35:40.545217+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.545251+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=2 WHOAMI 347s 2024-03-23T21:35:40.545279+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=2 RESULT oid= err=0 qtime=0.000058 etime=0.001072 text= 347s 2024-03-23T21:35:40.545306+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 op=3 UNBIND 347s 2024-03-23T21:35:40.545335+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1006 fd=12 closed 347s 2024-03-23T21:35:40.545521+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 fd=12 ACCEPT from IP=[::1]:49108 (IP=[::]:389) 347s 2024-03-23T21:35:40.545660+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.567295+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=0 RESULT tag=97 err=14 qtime=0.000012 etime=0.011334 text=SASL(0): successful result: user: user3753@example.fake property: slapAuthzDN not found in sasldb 347s 2024-03-23T21:35:40.568481+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.568553+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.568604+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=1 BIND dn="uid=user3753@example.fake,cn=scram-sha-1,cn=auth" mech=SCRAM-SHA-1 bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.568664+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=1 RESULT tag=97 err=0 qtime=0.000009 etime=0.000195 text= 347s 2024-03-23T21:35:40.568808+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.568866+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=2 WHOAMI 347s 2024-03-23T21:35:40.568928+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=2 RESULT oid= err=0 qtime=0.000003 etime=0.000129 text= 347s 2024-03-23T21:35:40.569044+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 op=3 UNBIND 347s 2024-03-23T21:35:40.569130+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1007 fd=12 closed 347s 2024-03-23T21:35:40.573677+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 fd=12 ACCEPT from IP=[::1]:49124 (IP=[::]:389) 347s 2024-03-23T21:35:40.577306+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.587299+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=0 RESULT tag=97 err=14 qtime=0.000004 etime=0.013433 text=SASL(0): successful result: user: user3753@example.fake property: slapAuthzDN not found in sasldb 347s 2024-03-23T21:35:40.596293+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.596373+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.596425+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=1 BIND dn="uid=user3753@example.fake,cn=scram-sha-224,cn=auth" mech=SCRAM-SHA-224 bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.596485+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=1 RESULT tag=97 err=0 qtime=0.000009 etime=0.000209 text= 347s 2024-03-23T21:35:40.596654+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.596711+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=2 WHOAMI 347s 2024-03-23T21:35:40.596770+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=2 RESULT oid= err=0 qtime=0.000020 etime=0.000157 text= 347s 2024-03-23T21:35:40.597365+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 op=3 UNBIND 347s 2024-03-23T21:35:40.597440+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1008 fd=12 closed 347s 2024-03-23T21:35:40.601595+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 fd=12 ACCEPT from IP=[::1]:49140 (IP=[::]:389) 347s 2024-03-23T21:35:40.607330+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.617350+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=0 RESULT tag=97 err=14 qtime=0.000003 etime=0.015560 text=SASL(0): successful result: user: user3753@example.fake property: slapAuthzDN not found in sasldb 347s 2024-03-23T21:35:40.626751+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.626826+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.626884+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=1 BIND dn="uid=user3753@example.fake,cn=scram-sha-256,cn=auth" mech=SCRAM-SHA-256 bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.626943+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=1 RESULT tag=97 err=0 qtime=0.000003 etime=0.000198 text= 347s 2024-03-23T21:35:40.627123+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.627151+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=2 WHOAMI 347s 2024-03-23T21:35:40.627176+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=2 RESULT oid= err=0 qtime=0.000003 etime=0.000020 text= 347s 2024-03-23T21:35:40.627292+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 op=3 UNBIND 347s 2024-03-23T21:35:40.627370+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1009 fd=12 closed 347s 2024-03-23T21:35:40.631773+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 fd=12 ACCEPT from IP=[::1]:49154 (IP=[::]:389) 347s 2024-03-23T21:35:40.637301+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.654732+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: connection_input: conn=1010 deferring operation: binding 347s 2024-03-23T21:35:40.654798+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=0 RESULT tag=97 err=14 qtime=0.000007 etime=0.022833 text=SASL(0): successful result: user: user3753@example.fake property: slapAuthzDN not found in sasldb 347s 2024-03-23T21:35:40.654863+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.654929+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.654985+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=1 BIND dn="uid=user3753@example.fake,cn=scram-sha-384,cn=auth" mech=SCRAM-SHA-384 bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.655048+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=1 RESULT tag=97 err=0 qtime=0.000138 etime=0.000323 text= 347s 2024-03-23T21:35:40.655222+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.655281+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=2 WHOAMI 347s 2024-03-23T21:35:40.655341+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=2 RESULT oid= err=0 qtime=0.000003 etime=0.000124 text= 347s 2024-03-23T21:35:40.655427+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 op=3 UNBIND 347s 2024-03-23T21:35:40.655499+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1010 fd=12 closed 347s 2024-03-23T21:35:40.659907+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 fd=12 ACCEPT from IP=[::1]:49170 (IP=[::]:389) 347s 2024-03-23T21:35:40.660117+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.677335+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=0 RESULT tag=97 err=14 qtime=0.000003 etime=0.017209 text=SASL(0): successful result: user: user3753@example.fake property: slapAuthzDN not found in sasldb 347s 2024-03-23T21:35:40.683013+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.683088+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.683156+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=1 BIND dn="uid=user3753@example.fake,cn=scram-sha-512,cn=auth" mech=SCRAM-SHA-512 bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.683202+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=1 RESULT tag=97 err=0 qtime=0.000005 etime=0.000151 text= 347s 2024-03-23T21:35:40.683418+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.683466+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=2 WHOAMI 347s 2024-03-23T21:35:40.683510+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=2 RESULT oid= err=0 qtime=0.000008 etime=0.000022 text= 347s 2024-03-23T21:35:40.683617+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 op=3 UNBIND 347s 2024-03-23T21:35:40.683663+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1011 fd=12 closed 347s 2024-03-23T21:35:40.688215+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 fd=12 ACCEPT from IP=[::1]:49186 (IP=[::]:389) 347s 2024-03-23T21:35:40.688634+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.688766+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=0 RESULT tag=97 err=14 qtime=0.000007 etime=0.000040 text=SASL(0): successful result: 347s 2024-03-23T21:35:40.689292+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=1 BIND dn="" method=163 347s 2024-03-23T21:35:40.689513+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 347s 2024-03-23T21:35:40.689556+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=1 BIND dn="uid=user3753@example.fake,cn=ntlm,cn=auth" mech=NTLM bind_ssf=0 ssf=0 347s 2024-03-23T21:35:40.689597+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=1 RESULT tag=97 err=0 qtime=0.000006 etime=0.000159 text= 347s 2024-03-23T21:35:40.689723+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 347s 2024-03-23T21:35:40.689770+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=2 WHOAMI 347s 2024-03-23T21:35:40.689812+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=2 RESULT oid= err=0 qtime=0.000007 etime=0.000024 text= 347s 2024-03-23T21:35:40.689927+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 op=3 UNBIND 347s 2024-03-23T21:35:40.689993+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1012 fd=12 closed 347s 2024-03-23T21:35:40.694043+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 fd=12 ACCEPT from IP=[::1]:49188 (IP=[::]:389) 347s 2024-03-23T21:35:40.694207+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=0 BIND dn="" method=163 347s 2024-03-23T21:35:40.694251+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=0 RESULT tag=97 err=14 qtime=0.000008 etime=0.000028 text=SASL(0): successful result: 347s 2024-03-23T21:35:40.694497+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=1 BIND dn="" method=163 348s 2024-03-23T21:35:40.694546+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 348s 2024-03-23T21:35:40.694591+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=1 BIND dn="uid=user3753@example.fake,cn=cram-md5,cn=auth" mech=CRAM-MD5 bind_ssf=0 ssf=0 348s 2024-03-23T21:35:40.694634+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=1 RESULT tag=97 err=0 qtime=0.000007 etime=0.000157 text= 348s 2024-03-23T21:35:40.694821+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=2 EXT oid=1.3.6.1.4.1.4203.1.11.3 348s 2024-03-23T21:35:40.694871+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=2 WHOAMI 348s 2024-03-23T21:35:40.694914+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=2 RESULT oid= err=0 qtime=0.000003 etime=0.000017 text= 348s 2024-03-23T21:35:40.695004+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 op=3 UNBIND 348s 2024-03-23T21:35:40.695062+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1013 fd=12 closed 348s 2024-03-23T21:35:40.792503+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 fd=12 ACCEPT from IP=[::1]:49204 (IP=[::]:389) 348s 2024-03-23T21:35:40.863696+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=0 BIND dn="" method=163 348s 2024-03-23T21:35:40.863829+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=0 RESULT tag=97 err=14 qtime=0.000013 etime=0.001357 text=SASL(0): successful result: 348s 2024-03-23T21:35:40.863916+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: connection_input: conn=1014 deferring operation: binding 348s 2024-03-23T21:35:40.864095+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=1 BIND dn="" method=163 348s 2024-03-23T21:35:40.864240+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=1 RESULT tag=97 err=14 qtime=0.000100 etime=0.000294 text=SASL(0): successful result: 348s 2024-03-23T21:35:40.864673+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=2 BIND dn="" method=163 348s 2024-03-23T21:35:40.864805+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=2 BIND authcid="user3753" authzid="user3753" 348s 2024-03-23T21:35:40.864937+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=2 BIND dn="uid=user3753,cn=gssapi,cn=auth" mech=GSSAPI bind_ssf=256 ssf=256 348s 2024-03-23T21:35:40.865101+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: connection_input: conn=1014 deferring operation: binding 348s 2024-03-23T21:35:40.865176+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=2 RESULT tag=97 err=0 qtime=0.000006 etime=0.000799 text= 348s 2024-03-23T21:35:40.866035+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=3 EXT oid=1.3.6.1.4.1.4203.1.11.3 348s 2024-03-23T21:35:40.866114+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=3 WHOAMI 348s 2024-03-23T21:35:40.866181+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=4 UNBIND 348s 2024-03-23T21:35:40.866240+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 op=3 RESULT oid= err=0 qtime=0.000167 etime=0.000859 text= 348s 2024-03-23T21:35:40.866297+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1014 fd=12 closed 348s 2024-03-23T21:35:40.871887+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 fd=12 ACCEPT from IP=[::1]:49210 (IP=[::]:389) 348s 2024-03-23T21:35:40.873101+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=0 BIND dn="" method=163 348s 2024-03-23T21:35:40.873163+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=0 BIND authcid="user3753" authzid="user3753" 348s 2024-03-23T21:35:40.873218+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=0 BIND dn="uid=user3753,cn=gss-spnego,cn=auth" mech=GSS-SPNEGO bind_ssf=256 ssf=256 348s 2024-03-23T21:35:40.873282+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=0 RESULT tag=97 err=0 qtime=0.000004 etime=0.000632 text= 348s 2024-03-23T21:35:40.873772+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=1 EXT oid=1.3.6.1.4.1.4203.1.11.3 348s 2024-03-23T21:35:40.873951+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=1 WHOAMI 348s 2024-03-23T21:35:40.874027+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=1 RESULT oid= err=0 qtime=0.000013 etime=0.000059 text= 348s 2024-03-23T21:35:40.874331+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 op=2 UNBIND 348s 2024-03-23T21:35:40.874554+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1015 fd=12 closed 348s 2024-03-23T21:35:40.889241+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopping autofs.service - Automounts filesystems on demand... 348s 2024-03-23T21:35:40.921456+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: net-localhost-nfspub.mount: Deactivated successfully. 348s 2024-03-23T21:35:41.351807+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: autofs.service: Deactivated successfully. 348s 2024-03-23T21:35:41.352932+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Stopped autofs.service - Automounts filesystems on demand. 348s 2024-03-23T21:35:41.381557+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose (utomount)[4049]: autofs.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS 348s 2024-03-23T21:35:41.381664+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Starting autofs.service - Automounts filesystems on demand... 348s 2024-03-23T21:35:41.399793+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose systemd[1]: Started autofs.service - Automounts filesystems on demand. 348s 2024-03-23T21:35:41.407381+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=0 BIND dn="" method=163 348s 2024-03-23T21:35:41.407428+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=0 RESULT tag=97 err=14 qtime=0.000010 etime=0.002501 text=SASL(0): successful result: 348s 2024-03-23T21:35:41.407462+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=1 BIND dn="" method=163 348s 2024-03-23T21:35:41.407496+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 fd=12 ACCEPT from IP=127.0.0.1:34228 (IP=0.0.0.0:389) 348s 2024-03-23T21:35:41.408365+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=1 BIND authcid="user3753@example.fake" authzid="user3753@example.fake" 348s 2024-03-23T21:35:41.408421+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=1 BIND dn="uid=user3753@example.fake,cn=digest-md5,cn=auth" mech=DIGEST-MD5 bind_ssf=128 ssf=128 348s 2024-03-23T21:35:41.408485+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=1 RESULT tag=97 err=0 qtime=0.000007 etime=0.002198 text= 348s 2024-03-23T21:35:41.408712+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=2 SRCH base="ou=auto.indirect,dc=example,dc=fake" scope=2 deref=0 filter="(objectClass=nisMap)" 348s 2024-03-23T21:35:41.408744+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=2 SRCH attr=nisMapName 348s 2024-03-23T21:35:41.408777+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=2 SEARCH RESULT tag=101 err=0 qtime=0.000004 etime=0.000117 nentries=0 text= 348s 2024-03-23T21:35:41.408965+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=3 SRCH base="ou=auto.indirect,dc=example,dc=fake" scope=2 deref=0 filter="(objectClass=automountMap)" 348s 2024-03-23T21:35:41.408995+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=3 SRCH attr=ou 348s 2024-03-23T21:35:41.409021+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=3 SEARCH RESULT tag=101 err=0 qtime=0.000003 etime=0.000073 nentries=1 text= 348s 2024-03-23T21:35:41.409224+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: 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)))" 348s 2024-03-23T21:35:41.409256+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=4 SRCH attr=cn automountInformation 348s 2024-03-23T21:35:41.409287+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=4 SEARCH RESULT tag=101 err=0 qtime=0.000003 etime=0.000082 nentries=1 text= 348s 2024-03-23T21:35:41.409415+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 op=5 UNBIND 348s 2024-03-23T21:35:41.409645+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose slapd[3997]: conn=1016 fd=12 closed 348s 2024-03-23T21:35:41.428599+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: netfs: FS-Cache loaded 348s 2024-03-23T21:35:41.767772+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: NFS: Registering the id_resolver key type 348s 2024-03-23T21:35:41.767811+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: Key type id_resolver registered 348s 2024-03-23T21:35:41.767822+00:00 adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-propose kernel: Key type id_legacy registered 348s 348s ## mounts: 348s sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) 348s proc on /proc type proc (rw,nosuid,nodev,noexec,relatime) 348s udev on /dev type devtmpfs (rw,nosuid,relatime,size=746816k,nr_inodes=186704,mode=755,inode64) 348s devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) 348s tmpfs on /run type tmpfs (rw,nosuid,nodev,noexec,relatime,size=151640k,mode=755,inode64) 348s /dev/vda1 on / type ext4 (rw,relatime,discard,errors=remount-ro,commit=30) 348s securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) 348s tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,inode64) 348s tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k,inode64) 348s cgroup2 on /sys/fs/cgroup type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot) 348s bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) 348s 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=1746) 348s hugetlbfs on /dev/hugepages type hugetlbfs (rw,nosuid,nodev,relatime,pagesize=1M) 348s mqueue on /dev/mqueue type mqueue (rw,nosuid,nodev,noexec,relatime) 348s debugfs on /sys/kernel/debug type debugfs (rw,nosuid,nodev,noexec,relatime) 348s tracefs on /sys/kernel/tracing type tracefs (rw,nosuid,nodev,noexec,relatime) 348s fusectl on /sys/fs/fuse/connections type fusectl (rw,nosuid,nodev,noexec,relatime) 348s configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime) 348s binfmt_misc on /proc/sys/fs/binfmt_misc type binfmt_misc (rw,nosuid,nodev,noexec,relatime) 348s tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=151636k,nr_inodes=37909,mode=700,uid=1000,gid=1000,inode64) 348s sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) 348s nfsd on /proc/fs/nfsd type nfsd (rw,relatime) 348s ldap://server.example.fake/ou=auto.indirect,dc=example,dc=fake on /mnt type autofs (rw,relatime,fd=7,pgrp=4050,timeout=300,minproto=5,maxproto=5,indirect,pipe_ino=18877) 348s autopkgtest [21:35:42]: test ldap-map-sasl-auth: -----------------------] 349s autopkgtest [21:35:43]: test ldap-map-sasl-auth: - - - - - - - - - - results - - - - - - - - - - 349s ldap-map-sasl-auth FAIL non-zero exit status 2 349s autopkgtest [21:35:43]: test ldap-map-sasl-auth: - - - - - - - - - - stderr - - - - - - - - - - 349s ls: cannot access '/mnt/storage/test_file_3753': No such file or directory 349s autopkgtest [21:35:43]: @@@@@@@@@@@@@@@@@@@@ summary 349s smb-mount PASS 349s nfs-mount PASS 349s ldap-map-sasl-auth FAIL non-zero exit status 2 366s Creating nova instance adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240323.img (UUID 15bd59e1-c29b-45d7-aba1-73ff15182fb5)... 366s Creating nova instance adt-noble-s390x-autofs-20240323-212954-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240323.img (UUID 15bd59e1-c29b-45d7-aba1-73ff15182fb5)...