0s autopkgtest [06:34:41]: starting date and time: 2024-03-24 06:34:41+0000 0s autopkgtest [06:34:41]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [06:34:41]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.6nu5wxpw/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:curl,src:gnutls28,src:libpsl,src:nettle,src:openssl,src:orthanc-python --apt-upgrade gssproxy --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=curl/8.5.0-2ubuntu8 gnutls28/3.8.3-1.1ubuntu2 libpsl/0.21.2-1.1 nettle/3.9.1-2.2 openssl/3.0.13-0ubuntu2 orthanc-python/4.1+ds-2build3' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-s390x-20.secgroup --name adt-noble-s390x-gssproxy-20240324-063441-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/ 187s autopkgtest [06:37:48]: testbed dpkg architecture: s390x 187s autopkgtest [06:37:48]: testbed apt version: 2.7.12 187s autopkgtest [06:37:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 188s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 188s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB] 189s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4009 kB] 193s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 193s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB] 193s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [691 kB] 193s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 193s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 193s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 193s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4191 kB] 196s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 196s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB] 196s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 198s Fetched 9625 kB in 9s (1047 kB/s) 198s Reading package lists... 200s Reading package lists... 201s Building dependency tree... 201s Reading state information... 201s Calculating upgrade... 201s The following packages will be REMOVED: 201s libssl3 201s The following NEW packages will be installed: 201s libssl3t64 201s The following packages have been kept back: 201s curl 201s The following packages will be upgraded: 201s openssl 201s 1 upgraded, 1 newly installed, 1 to remove and 1 not upgraded. 201s Need to get 2685 kB of archives. 201s After this operation, 239 kB of additional disk space will be used. 201s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 202s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 202s Fetched 2685 kB in 1s (2869 kB/s) 202s (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.) 202s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 202s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 202s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 202s wget depends on libssl3 (>= 3.0.0). 202s tnftp depends on libssl3 (>= 3.0.0). 202s tcpdump depends on libssl3 (>= 3.0.0). 202s systemd-resolved depends on libssl3 (>= 3.0.0). 202s systemd depends on libssl3 (>= 3.0.0). 202s sudo depends on libssl3 (>= 3.0.0). 202s s390-tools depends on libssl3 (>= 3.0.0). 202s rsync depends on libssl3 (>= 3.0.0). 202s python3-cryptography depends on libssl3 (>= 3.0.0). 202s openssh-server depends on libssl3 (>= 3.0.10). 202s openssh-client depends on libssl3 (>= 3.0.10). 202s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 202s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 202s libssh-4:s390x depends on libssl3 (>= 3.0.0). 202s libsasl2-modules:s390x depends on libssl3 (>= 3.0.0). 202s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 202s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 202s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 202s libnvme1 depends on libssl3 (>= 3.0.0). 202s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 202s libkmod2:s390x depends on libssl3 (>= 3.0.0). 202s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 202s libcurl4:s390x depends on libssl3 (>= 3.0.0). 202s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 202s kmod depends on libssl3 (>= 3.0.0). 202s dhcpcd-base depends on libssl3 (>= 3.0.0). 202s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 202s 202s (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.) 202s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 202s Selecting previously unselected package libssl3t64:s390x. 202s (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 ... 52159 files and directories currently installed.) 202s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 202s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 203s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 203s Setting up openssl (3.0.13-0ubuntu2) ... 203s Processing triggers for man-db (2.12.0-3) ... 203s Processing triggers for libc-bin (2.39-0ubuntu6) ... 203s Reading package lists... 203s Building dependency tree... 203s Reading state information... 204s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 204s Unknown architecture, assuming PC-style ttyS0 204s sh: Attempting to set up Debian/Ubuntu apt sources automatically 204s sh: Distribution appears to be Ubuntu 205s Reading package lists... 205s Building dependency tree... 205s Reading state information... 205s eatmydata is already the newest version (131-1). 205s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 205s Reading package lists... 205s Building dependency tree... 205s Reading state information... 205s dbus is already the newest version (1.14.10-4ubuntu1). 205s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 205s Reading package lists... 206s Building dependency tree... 206s Reading state information... 206s rng-tools-debian is already the newest version (2.4). 206s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 206s Reading package lists... 206s Building dependency tree... 206s Reading state information... 206s The following packages will be REMOVED: 206s cloud-init* python3-configobj* python3-debconf* 206s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 206s After this operation, 3256 kB disk space will be freed. 206s (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 ... 52172 files and directories currently installed.) 206s Removing cloud-init (24.1.2-0ubuntu1) ... 207s Removing python3-configobj (5.0.8-3) ... 207s Removing python3-debconf (1.5.86) ... 207s Processing triggers for man-db (2.12.0-3) ... 207s (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 ... 51783 files and directories currently installed.) 207s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 208s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 208s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 208s invoke-rc.d: policy-rc.d denied execution of try-restart. 208s Reading package lists... 208s Building dependency tree... 208s Reading state information... 208s linux-generic is already the newest version (6.8.0-11.11+1). 208s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 209s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 209s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 209s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 210s Reading package lists... 210s Reading package lists... 211s Building dependency tree... 211s Reading state information... 211s Calculating upgrade... 211s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 211s Reading package lists... 211s Building dependency tree... 211s Reading state information... 211s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 211s autopkgtest [06:38:12]: rebooting testbed after setup commands that affected boot 226s autopkgtest [06:38:27]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 229s autopkgtest [06:38:30]: @@@@@@@@@@@@@@@@@@@@ apt-source gssproxy 230s Get:1 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (dsc) [1699 B] 230s Get:2 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (tar) [190 kB] 230s Get:3 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (diff) [6864 B] 231s gpgv: Signature made Fri Jan 12 10:48:47 2024 UTC 231s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 231s gpgv: issuer "simon@josefsson.org" 231s gpgv: Can't check signature: No public key 231s dpkg-source: warning: cannot verify inline signature for ./gssproxy_0.9.2-2.dsc: no acceptable signature found 231s autopkgtest [06:38:32]: testing package gssproxy version 0.9.2-2 231s autopkgtest [06:38:32]: build not needed 231s autopkgtest [06:38:32]: test gssproxy-apache: preparing testbed 233s Reading package lists... 233s Building dependency tree... 233s Reading state information... 233s Starting pkgProblemResolver with broken count: 0 233s Starting 2 pkgProblemResolver with broken count: 0 233s Done 233s The following additional packages will be installed: 233s apache2 apache2-bin apache2-data apache2-utils gssproxy krb5-admin-server 233s krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi libapr1 233s libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libbasicobjects0 233s libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 libkadm5clnt-mit12 233s libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 libref-array1 233s libverto-libevent1 libverto1 233s Suggested packages: 233s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 233s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 233s Recommended packages: 233s ssl-cert 233s The following NEW packages will be installed: 233s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep gssproxy 233s krb5-admin-server krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi 233s libapr1 libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap 233s libbasicobjects0 libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 233s libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 233s libref-array1 libverto-libevent1 libverto1 233s 0 upgraded, 28 newly installed, 0 to remove and 0 not upgraded. 233s Need to get 3212 kB/3212 kB of archives. 233s After this operation, 11.4 MB of additional disk space will be used. 233s Get:1 /tmp/autopkgtest.6IH21s/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [752 B] 233s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libapr1 s390x 1.7.2-3 [105 kB] 234s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1 s390x 1.6.3-1ubuntu1 [91.9 kB] 234s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1-dbd-sqlite3 s390x 1.6.3-1ubuntu1 [11.2 kB] 234s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1-ldap s390x 1.6.3-1ubuntu1 [8914 B] 234s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x liblua5.4-0 s390x 5.4.6-3 [179 kB] 234s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x apache2-bin s390x 2.4.58-1ubuntu2 [1412 kB] 234s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x apache2-data all 2.4.58-1ubuntu2 [163 kB] 234s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x apache2-utils s390x 2.4.58-1ubuntu2 [99.6 kB] 234s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x apache2 s390x 2.4.58-1ubuntu2 [90.2 kB] 234s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x krb5-config all 2.7 [22.0 kB] 234s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x libgssrpc4 s390x 1.20.1-5build1 [58.9 kB] 234s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5clnt-mit12 s390x 1.20.1-5build1 [40.5 kB] 234s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x libkdb5-10 s390x 1.20.1-5build1 [41.4 kB] 234s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5srv-mit12 s390x 1.20.1-5build1 [55.2 kB] 234s Get:16 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-user s390x 1.20.1-5build1 [110 kB] 234s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x libevent-2.1-7 s390x 2.1.12-stable-9 [144 kB] 234s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x libverto1 s390x 0.3.1-1ubuntu5 [10.6 kB] 234s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x libverto-libevent1 s390x 0.3.1-1ubuntu5 [5810 B] 234s Get:20 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-kdc s390x 1.20.1-5build1 [188 kB] 234s Get:21 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-admin-server s390x 1.20.1-5build1 [95.9 kB] 234s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x libbasicobjects0 s390x 0.6.2-2 [5476 B] 234s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x libcollection4 s390x 0.6.2-2 [23.2 kB] 234s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x libpath-utils1 s390x 0.6.2-2 [8994 B] 234s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x libref-array1 s390x 0.6.2-2 [6880 B] 234s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libini-config5 s390x 0.6.2-2 [45.3 kB] 234s Get:27 http://ftpmaster.internal/ubuntu noble/universe s390x gssproxy s390x 0.9.2-2 [126 kB] 234s Get:28 http://ftpmaster.internal/ubuntu noble/universe s390x libapache2-mod-auth-gssapi s390x 1.6.4-3 [62.5 kB] 234s Preconfiguring packages ... 235s Fetched 3212 kB in 1s (3330 kB/s) 235s Selecting previously unselected package libapr1:s390x. 235s (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 ... 51728 files and directories currently installed.) 235s Preparing to unpack .../00-libapr1_1.7.2-3_s390x.deb ... 235s Unpacking libapr1:s390x (1.7.2-3) ... 235s Selecting previously unselected package libaprutil1:s390x. 235s Preparing to unpack .../01-libaprutil1_1.6.3-1ubuntu1_s390x.deb ... 235s Unpacking libaprutil1:s390x (1.6.3-1ubuntu1) ... 235s Selecting previously unselected package libaprutil1-dbd-sqlite3:s390x. 235s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-1ubuntu1_s390x.deb ... 235s Unpacking libaprutil1-dbd-sqlite3:s390x (1.6.3-1ubuntu1) ... 235s Selecting previously unselected package libaprutil1-ldap:s390x. 235s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-1ubuntu1_s390x.deb ... 235s Unpacking libaprutil1-ldap:s390x (1.6.3-1ubuntu1) ... 235s Selecting previously unselected package liblua5.4-0:s390x. 235s Preparing to unpack .../04-liblua5.4-0_5.4.6-3_s390x.deb ... 235s Unpacking liblua5.4-0:s390x (5.4.6-3) ... 235s Selecting previously unselected package apache2-bin. 235s Preparing to unpack .../05-apache2-bin_2.4.58-1ubuntu2_s390x.deb ... 235s Unpacking apache2-bin (2.4.58-1ubuntu2) ... 235s Selecting previously unselected package apache2-data. 235s Preparing to unpack .../06-apache2-data_2.4.58-1ubuntu2_all.deb ... 235s Unpacking apache2-data (2.4.58-1ubuntu2) ... 235s Selecting previously unselected package apache2-utils. 235s Preparing to unpack .../07-apache2-utils_2.4.58-1ubuntu2_s390x.deb ... 235s Unpacking apache2-utils (2.4.58-1ubuntu2) ... 235s Selecting previously unselected package apache2. 235s Preparing to unpack .../08-apache2_2.4.58-1ubuntu2_s390x.deb ... 235s Unpacking apache2 (2.4.58-1ubuntu2) ... 235s Selecting previously unselected package krb5-config. 235s Preparing to unpack .../09-krb5-config_2.7_all.deb ... 235s Unpacking krb5-config (2.7) ... 235s Selecting previously unselected package libgssrpc4:s390x. 235s Preparing to unpack .../10-libgssrpc4_1.20.1-5build1_s390x.deb ... 235s Unpacking libgssrpc4:s390x (1.20.1-5build1) ... 235s Selecting previously unselected package libkadm5clnt-mit12:s390x. 235s Preparing to unpack .../11-libkadm5clnt-mit12_1.20.1-5build1_s390x.deb ... 235s Unpacking libkadm5clnt-mit12:s390x (1.20.1-5build1) ... 235s Selecting previously unselected package libkdb5-10:s390x. 235s Preparing to unpack .../12-libkdb5-10_1.20.1-5build1_s390x.deb ... 235s Unpacking libkdb5-10:s390x (1.20.1-5build1) ... 235s Selecting previously unselected package libkadm5srv-mit12:s390x. 235s Preparing to unpack .../13-libkadm5srv-mit12_1.20.1-5build1_s390x.deb ... 235s Unpacking libkadm5srv-mit12:s390x (1.20.1-5build1) ... 235s Selecting previously unselected package krb5-user. 235s Preparing to unpack .../14-krb5-user_1.20.1-5build1_s390x.deb ... 235s Unpacking krb5-user (1.20.1-5build1) ... 235s Selecting previously unselected package libevent-2.1-7:s390x. 235s Preparing to unpack .../15-libevent-2.1-7_2.1.12-stable-9_s390x.deb ... 235s Unpacking libevent-2.1-7:s390x (2.1.12-stable-9) ... 235s Selecting previously unselected package libverto1:s390x. 235s Preparing to unpack .../16-libverto1_0.3.1-1ubuntu5_s390x.deb ... 235s Unpacking libverto1:s390x (0.3.1-1ubuntu5) ... 235s Selecting previously unselected package libverto-libevent1:s390x. 235s Preparing to unpack .../17-libverto-libevent1_0.3.1-1ubuntu5_s390x.deb ... 235s Unpacking libverto-libevent1:s390x (0.3.1-1ubuntu5) ... 235s Selecting previously unselected package krb5-kdc. 235s Preparing to unpack .../18-krb5-kdc_1.20.1-5build1_s390x.deb ... 235s Unpacking krb5-kdc (1.20.1-5build1) ... 235s Selecting previously unselected package krb5-admin-server. 235s Preparing to unpack .../19-krb5-admin-server_1.20.1-5build1_s390x.deb ... 235s Unpacking krb5-admin-server (1.20.1-5build1) ... 235s Selecting previously unselected package libbasicobjects0:s390x. 235s Preparing to unpack .../20-libbasicobjects0_0.6.2-2_s390x.deb ... 235s Unpacking libbasicobjects0:s390x (0.6.2-2) ... 235s Selecting previously unselected package libcollection4:s390x. 235s Preparing to unpack .../21-libcollection4_0.6.2-2_s390x.deb ... 235s Unpacking libcollection4:s390x (0.6.2-2) ... 235s Selecting previously unselected package libpath-utils1:s390x. 235s Preparing to unpack .../22-libpath-utils1_0.6.2-2_s390x.deb ... 235s Unpacking libpath-utils1:s390x (0.6.2-2) ... 235s Selecting previously unselected package libref-array1:s390x. 235s Preparing to unpack .../23-libref-array1_0.6.2-2_s390x.deb ... 235s Unpacking libref-array1:s390x (0.6.2-2) ... 235s Selecting previously unselected package libini-config5:s390x. 235s Preparing to unpack .../24-libini-config5_0.6.2-2_s390x.deb ... 235s Unpacking libini-config5:s390x (0.6.2-2) ... 235s Selecting previously unselected package gssproxy. 235s Preparing to unpack .../25-gssproxy_0.9.2-2_s390x.deb ... 235s Unpacking gssproxy (0.9.2-2) ... 235s Selecting previously unselected package libapache2-mod-auth-gssapi. 235s Preparing to unpack .../26-libapache2-mod-auth-gssapi_1.6.4-3_s390x.deb ... 235s Unpacking libapache2-mod-auth-gssapi (1.6.4-3) ... 235s Selecting previously unselected package autopkgtest-satdep. 235s Preparing to unpack .../27-1-autopkgtest-satdep.deb ... 235s Unpacking autopkgtest-satdep (0) ... 235s Setting up libpath-utils1:s390x (0.6.2-2) ... 235s Setting up libbasicobjects0:s390x (0.6.2-2) ... 235s Setting up libapr1:s390x (1.7.2-3) ... 235s Setting up libgssrpc4:s390x (1.20.1-5build1) ... 235s Setting up krb5-config (2.7) ... 235s Setting up libevent-2.1-7:s390x (2.1.12-stable-9) ... 235s Setting up libcollection4:s390x (0.6.2-2) ... 235s Setting up liblua5.4-0:s390x (5.4.6-3) ... 235s Setting up libref-array1:s390x (0.6.2-2) ... 235s Setting up apache2-data (2.4.58-1ubuntu2) ... 235s Setting up libkadm5clnt-mit12:s390x (1.20.1-5build1) ... 235s Setting up libaprutil1:s390x (1.6.3-1ubuntu1) ... 235s Setting up libkdb5-10:s390x (1.20.1-5build1) ... 235s Setting up libini-config5:s390x (0.6.2-2) ... 235s Setting up libaprutil1-ldap:s390x (1.6.3-1ubuntu1) ... 235s Setting up libaprutil1-dbd-sqlite3:s390x (1.6.3-1ubuntu1) ... 235s Setting up libkadm5srv-mit12:s390x (1.20.1-5build1) ... 235s Setting up apache2-utils (2.4.58-1ubuntu2) ... 235s Setting up apache2-bin (2.4.58-1ubuntu2) ... 235s Setting up krb5-user (1.20.1-5build1) ... 235s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 235s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 235s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 235s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 235s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 235s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 235s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 235s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 235s Setting up libapache2-mod-auth-gssapi (1.6.4-3) ... 235s Package apache2 is not configured yet. Will defer actions by package libapache2-mod-auth-gssapi. 235s Setting up apache2 (2.4.58-1ubuntu2) ... 235s Enabling module mpm_event. 235s Enabling module authz_core. 235s Enabling module authz_host. 236s Enabling module authn_core. 236s Enabling module auth_basic. 236s Enabling module access_compat. 236s Enabling module authn_file. 236s Enabling module authz_user. 236s Enabling module alias. 236s Enabling module dir. 236s Enabling module autoindex. 236s Enabling module env. 236s Enabling module mime. 236s Enabling module negotiation. 236s Enabling module setenvif. 236s Enabling module filter. 236s Enabling module deflate. 236s Enabling module status. 236s Enabling module reqtimeout. 236s Enabling conf charset. 236s Enabling conf localized-error-pages. 236s Enabling conf other-vhosts-access-log. 236s Enabling conf security. 236s Enabling conf serve-cgi-bin. 236s Enabling site 000-default. 236s info: Executing deferred 'a2enmod auth_gssapi' for package libapache2-mod-auth-gssapi 236s Enabling module auth_gssapi. 237s Created symlink /etc/systemd/system/multi-user.target.wants/apache2.service → /usr/lib/systemd/system/apache2.service. 237s Created symlink /etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service → /usr/lib/systemd/system/apache-htcacheclean.service. 238s Setting up libverto-libevent1:s390x (0.3.1-1ubuntu5) ... 238s Setting up libverto1:s390x (0.3.1-1ubuntu5) ... 238s Setting up gssproxy (0.9.2-2) ... 238s Created symlink /etc/systemd/user/sockets.target.wants/gssuserproxy.socket → /usr/lib/systemd/user/gssuserproxy.socket. 238s Created symlink /etc/systemd/system/multi-user.target.wants/gssproxy.service → /usr/lib/systemd/system/gssproxy.service. 239s Setting up krb5-kdc (1.20.1-5build1) ... 239s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service. 240s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 240s Setting up krb5-admin-server (1.20.1-5build1) ... 240s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service. 240s Setting up autopkgtest-satdep (0) ... 240s Processing triggers for libc-bin (2.39-0ubuntu6) ... 241s Processing triggers for ufw (0.36.2-5) ... 241s Processing triggers for man-db (2.12.0-3) ... 244s (Reading database ... 52636 files and directories currently installed.) 244s Removing autopkgtest-satdep (0) ... 244s autopkgtest [06:38:45]: test gssproxy-apache: [----------------------- 244s /usr/bin/ss 244s /usr/bin/id 244s /usr/bin/hostname 244s /usr/bin/dpkg 244s + command -v ss 244s + command -v id 244s + command -v hostname 244s + command -v dpkg 244s + dpkg -V krb5-kdc 244s + dpkg -V krb5-admin-server 244s + dpkg -V apache2 244s + dpkg -V libapache2-mod-auth-gssapi 244s + dpkg -V gssproxy 244s + dpkg -V curl 244s + service apache2 stop 245s + service krb5-kdc stop 246s + command -v systemctl 246s + /usr/bin/systemctl 246s service gssproxy stop 246s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 246s + trap 246s set +e; 246s kdestroy 246s service apache2 stop 246s service krb5-kdc stop 246s for f in /tmp/out-*; 246s do echo ----- BEGIN FILE $f ----- 246s cat $f 246s done 246s tail -v /var/log/apache2/error.log /var/log/apache2/access.log 246s mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 246s mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 246s mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 246s rm /etc/gssproxy/80-gssproxy-autopkgtest.conf /etc/apache2/conf-enabled/gssproxy-autopkgtest.conf /etc/systemd/system/apache2.service.d/gssproxy-autopkgtest.conf /var/www/html/gssproxy-autopkgtest/foo /var/lib/krb5kdc/gssproxy-principal* /var/lib/krb5kdc/gssproxy-stash /etc/gssproxy/httpd.keytab 246s test -d /etc/systemd/system/apache2.service.d && rmdir /etc/systemd/system/apache2.service.d 246s rmdir /var/www/html/gssproxy-autopkgtest 246s 0 INT QUIT ABRT PIPE TERM 246s + tail --pid=2253 -F /var/log/syslog 246s + id -un 246s + my_user=root 246s + hostname2024-03-24T06:38:40.434122+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo (kadmind)[2116]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 246s 2024-03-24T06:38:40.434230+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo kadmind[2116]: kadmind: Cannot open DB2 database '/var/lib/krb5kdc/principal': No such file or directory while initializing, aborting 246s 2024-03-24T06:38:40.435832+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Main process exited, code=exited, status=1/FAILURE 246s 2024-03-24T06:38:40.435979+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Failed with result 'exit-code'. 246s 2024-03-24T06:38:44.847376+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 246s 2024-03-24T06:38:44.884262+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 246s 2024-03-24T06:38:44.884709+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 246s 2024-03-24T06:38:45.981765+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopping gssproxy.service - GSSAPI Proxy Daemon... 246s 2024-03-24T06:38:45.983687+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: gssproxy.service: Deactivated successfully. 246s 2024-03-24T06:38:45.983931+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopped gssproxy.service - GSSAPI Proxy Daemon. 247s -f 247s + my_hostname=adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal 247s + hostname -d 247s + my_domainname=novalocal 247s + test -f /etc/krb5kdc/kdc.conf-gssproxysave 247s + cp /etc/krb5kdc/kdc.conf /etc/krb5kdc/kdc.conf-gssproxysave 247s + cat 247s + test -f /etc/krb5.conf-gssproxysave 247s + cp /etc/krb5.conf /etc/krb5.conf-gssproxysave 247s + cat 247s + kdb5_util -P foo create -s 247s + kadmin.local addprinc -randkey HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal 247s + kadmin.local addprinc -pw bar root 247s + kadmin.local ktadd -k /etc/gssproxy/httpd.keytab HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal 247s + service krb5-kdc start 247s Initializing database '/var/lib/krb5kdc/gssproxy-principal' for realm 'GSSPROXY.EXAMPLE.ORG', 247s master key name 'K/M@GSSPROXY.EXAMPLE.ORG' 247s Entry for principal HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 247s Entry for principal HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 247s 2024-03-24T06:38:46.623978+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 247s 2024-03-24T06:38:46.624933+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo (krb5kdc)[2303]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 247s + i=0 247s + + grep 0.0.0.0:17643 247s + grep LISTEN 247s ss -na 247s 2024-03-24T06:38:46.637251+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center. 247s + tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 247s kdestroy 247s kdestroy: No credentials cache found while destroying cache 247s + echo bar 247s + kinit root 247s Password for root@GSSPROXY.EXAMPLE.ORG: 247s + test -f /etc/gssproxy/gssproxy.conf-gssproxysave 247s + cp /etc/gssproxy/gssproxy.conf /etc/gssproxy/gssproxy.conf-gssproxysave 247s + cat 247s + cat 247s + command -v systemctl 247s + cat 247s + KRB5RCACHEDIR=/var/lib/gssproxy/rcache /usr/sbin/gssproxy -i --debug-level=2 247s + command -v systemctl 247s + mkdir -p /etc/systemd/system/apache2.service.d 247s + cat 247s /usr/bin/systemctl 247s /usr/bin/systemctl 247s + systemctl daemon-reload 247s 2024-03-24T06:38:46.704144+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Reloading requested from client PID 2331 ('systemctl') (unit session-1.scope)... 247s 2024-03-24T06:38:46.704217+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Reloading... 247s 2024-03-24T06:38:46.940877+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Reloading finished in 237 ms. 247s + service apache2 start 248s 2024-03-24T06:38:47.537340+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Starting apache2.service - The Apache HTTP Server... 248s + mkdir -p /var/www/html/gssproxy-autopkgtest 248s + 2024-03-24T06:38:47.554233+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Started apache2.service - The Apache HTTP Server. 248s echo Ossifrage 248s + curl --verbose --negotiate -u : http://adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal/gssproxy-autopkgtest/foo 248s + grep Ossifrage /tmp/out-curl-out 248s + echo PASS: /tmp/autopkgtest.6IH21s/build.wvw/src/debian/tests/gssproxy-apache 248s + exit 0 248s + set +e 248s + kdestroy 248s + service apache2 stop 248s Ossifrage 248s PASS: /tmp/autopkgtest.6IH21s/build.wvw/src/debian/tests/gssproxy-apache 248s 2024-03-24T06:38:48.147458+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 248s 2024-03-24T06:38:48.182816+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 248s 2024-03-24T06:38:48.183020+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 248s + service krb5-kdc stop 249s 2024-03-24T06:38:48.724370+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopping krb5-kdc.service - Kerberos 5 Key Distribution Center... 249s + echo ----- BEGIN FILE /tmp/out-curl-err ----- 249s + cat /tmp/out-curl-err 249s + echo ----- BEGIN FILE /tmp/out-curl-out ----- 249s + cat /tmp/out-curl-out 249s ----- BEGIN FILE /tmp/out-curl-err ----- 249s + echo ----- BEGIN FILE /tmp/out-gssproxy-err ----- 249s + cat /tmp/out-gssproxy-err 249s * Uses proxy env variable 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' 249s % Total % Received % Xferd Average Speed Time Time Time Current 249s Dload Upload Total Spent Left Speed 249s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0* Host adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal:80 was resolved. 249s * IPv6: (none) 249s * IPv4: 127.0.1.1 249s * Trying 127.0.1.1:80... 249s * Connected to adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal (127.0.1.1) port 80 249s * Server auth using Negotiate with user '' 249s > GET /gssproxy-autopkgtest/foo HTTP/1.1 249s > Host: adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal 249s > Authorization: Negotiate YIIDTgYGKwYBBQUCoIIDQjCCAz6gDTALBgkqhkiG9xIBAgKiggMrBIIDJ2CCAyMGCSqGSIb3EgECAgEAboIDEjCCAw6gAwIBBaEDAgEOogcDBQAgAAAAo4ICHmGCAhowggIWoAMCAQWhFhsUR1NTUFJPWFkuRVhBTVBMRS5PUkeiXDBaoAMCAQOhUzBRGwRIVFRQG0lhZHQtbm9ibGUtczM5MHgtZ3NzcHJveHktMjAyNDAzMjQtMDYzNDQxLWp1anUtN2YyMjc1LXByb2QtcHJvcG8ubm92YWxvY2Fso4IBlzCCAZOgAwIBEqEDAgECooIBhQSCAYHSdtG4yOA/aia7rpy+YcQTPYUf2rsO0xtcbBHhTpfm9COg4H4KrvxAGtMZzbUbw/N0HZU/AcuiUxZawn5WuNF4P7n3df5gZV3bftyAx1W2V9AFcN3PVepfBhgl8QQ7GBtbCpybzsAg3jwmTnkZa9AECj2+PHo2T2xwveVcyb9E4LO05z6JqOcVfNWEJLeCVLDIilJ2LJ1ZPmdIWn0WqR+7fMRbdWPimtICdln7aSJvQd1F64WWdLhiFscsRioAfGHnHzVtqklxa8CS17hO/txjrPC92Hb4bM/2k828AZIGyIZucXYPc22R0LSHm/vxbIczw242piPuaoKUH8X3KPkiiCx1n5kt9nfVzA00nVq7ldlrLbFh3ZCFYD4Mo2/nDtgnRxPj3MkgJU8NcxAa9P+lC9FcyCVxtT9ak9iMhBnS8t4c0JmNYW2id2ve+xZCRIQNMgZadmYVpvg8gQJYI7KFrd3iu59mdC09hjliO5/PZwVpAeUlU37dRNDAva2jtftlpIHWMIHToAMCARKigcsEgchmvuklJ/4DuLsFMHcGKSAqhNX0RACy3S6D/IxCbOxT4PCaNBI+JvJm9Rs3oZrY79Mc4zrhCZoZfR8ZkZCIEF0FH7KJk4H2mHNTx87xX8H5XK+OBqNaN27OfWVYWBVlMT1StbJKUnOVug2FhW7uJWkj0MQduhmVo1kJLuNbHrOjC9jVaXZ7rKaYqUB04HdZzPW3mWwrJyVpaftWHiZ+xCvpMBM26xa11EoH+OIB1g5UdjWKUVTHej2viL9rzMLN/XgT03J4oIHigg== 249s > User-Agent: curl/8.5.0 249s > Accept: */* 249s > 249s < HTTP/1.1 200 OK 249s < Date: Sun, 24 Mar 2024 06:38:47 GMT 249s < Server: Apache/2.4.58 (Ubuntu) 249s < WWW-Authenticate: Negotiate oYG3MIG0oAMKAQChCwYJKoZIhvcSAQICooGfBIGcYIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvp3+cY/4tJHZWQ9l8tIWZV0AsNZmUmufPcGhQ4lTpAUIZUNdfESOD+eqixGYU1iSamILH7GBuTMO2MGwViyuDWiN/MevFiGeROesTd7LAxlWJOX3AjCKaWlbbLB/eFny9JCCqzcKUkxofYvacqAZn 249s < Last-Modified: Sun, 24 Mar 2024 06:38:47 GMT 249s < ETag: W/"a-614624f26b451" 249s < Accept-Ranges: bytes 249s < Content-Length: 10 249s < 249s { [10 bytes data] 249s 100 10 100 10 0 0 878 0 --:--:-- --:--:-- --:--:-- 909 249s * Connection #0 to host adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal left intact 249s ----- BEGIN FILE /tmp/out-curl-out ----- 249s 2024-03-24T06:38:48.728915+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: krb5-kdc.service: Deactivated successfully. 249s 2024-03-24T06:38:48.728983+00:00 adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo systemd[1]: Stopped krb5-kdc.service - Kerberos 5 Key Distribution Center. 249s Ossifrage 249s ----- BEGIN FILE /tmp/out-gssproxy-err ----- 249s [2024/03/24 06:38:46]: Debug Level changed to 2 249s [2024/03/24 06:38:46]: Debug Level changed to 2 249s [2024/03/24 06:38:46]: Keytab /etc/krb5.keytab has no content (-1765328203) 249s [2024/03/24 06:38:46]: Service: nfs-server, Enckey: [ephemeral], Enctype: 18 249s [2024/03/24 06:38:46]: Service: HTTP, Keytab: /etc/gssproxy/httpd.keytab, Enctype: 18 249s [2024/03/24 06:38:46]: Kernel doesn't support GSS-Proxy (can't open /proc/net/rpc/use-gss-proxy: 2 (No such file or directory)) 249s [2024/03/24 06:38:46]: Initialization complete. 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 4 249s [2024/03/24 06:38:47]: Idle for: 1711262327 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 56 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 60 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 112 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 116 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 168 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 172 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 224 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 228 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 280 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 284 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 336 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 340 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 392 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 396 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 448 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2376) (uid = 0) (gid = 0)[2024/03/24 06:38:47]: 249s [2024/03/24 06:38:47]: Total received bytes: 452 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: No matching service found 249s [2024/03/24 06:38:47]: Total received bytes: 504 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Client [2024/03/24 06:38:47]: (/usr/sbin/apache2) [2024/03/24 06:38:47]: connected (fd = 11)[2024/03/24 06:38:47]: (pid = 2382) (uid = 33) (gid = 33)[2024/03/24 06:38:47]: 249s [CID 11][2024/03/24 06:38:47]: Connection matched service HTTP 249s [CID 11][2024/03/24 06:38:47]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 249s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: add_cred: 0 desired_name: time_req: 4294967295 desired_mechs: { { 1 2 840 113554 1 2 2 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 ) 249s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .D................. ] 0 } ) 249s [2024/03/24 06:38:47]: Total received bytes: 624 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Total sent bytes: 1044 249s [2024/03/24 06:38:47]: Total received bytes: 1700 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: Connection matched service HTTP 249s [CID 11][2024/03/24 06:38:47]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 249s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .D................. ] 0 } add_cred: 0 desired_name: time_req: 4294967295 desired_mechs: { { 1 3 6 1 5 2 5 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 ) 249s GSSX_RES_ACQUIRE_CRED( status: { 458752 0 "No credentials were supplied, or the credentials were unavailable or inaccessible" "" [ ] } output_cred_handle: ) 249s [2024/03/24 06:38:47]: Total sent bytes: 1200 249s [2024/03/24 06:38:47]: Total received bytes: 1704 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Total received bytes: 1756 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: Connection matched service HTTP 249s [CID 11][2024/03/24 06:38:47]: gp_rpc_execute: executing 1 (GSSX_INDICATE_MECHS) for service "HTTP", euid: 33,socket: (null) 249s GSSX_ARG_INDICATE_MECHS( call_ctx: { "" [ ] } ) 249s GSSX_RES_INDICATE_MECHS( status: { 0 0 "" "" [ ] } mechs: [ { { 1 2 840 113554 1 2 2 } { { 1 2 840 113554 1 2 1 1 } { 1 2 840 113554 1 2 1 2 } { 1 2 840 113554 1 2 1 3 } { 1 2 840 113554 1 2 1 4 } { 1 3 6 1 5 6 2 } { 1 3 6 1 5 6 4 } { 1 2 840 113554 1 2 2 1 } { 1 3 6 1 5 6 6 } { 1 2 840 113554 1 2 2 2 } } { { 1 3 6 1 5 5 13 1 } { 1 3 6 1 5 5 13 9 } { 1 3 6 1 5 5 13 10 } { 1 3 6 1 5 5 13 11 } { 1 3 6 1 5 5 13 16 } { 1 3 6 1 5 5 13 17 } { 1 3 6 1 5 5 13 18 } { 1 3 6 1 5 5 13 19 } { 1 3 6 1 5 5 13 20 } { 1 3 6 1 5 5 13 21 } { 1 3 6 1 5 5 13 22 } { 1 3 6 1 5 5 13 23 } { 1 3 6 1 5 5 13 24 } { 1 3 6 1 5 5 13 27 } } { { 1 3 6 1 5 5 13 1 } { 1 3 6 1 5 5 13 2 } { 1 3 6 1 5 5 13 3 } { 1 3 6 1 5 5 13 4 } { 1 3 6 1 5 5 13 5 } { 1 3 6 1 5 5 13 6 } { 1 3 6 1 5 5 13 7 } { 1 3 6 1 5 5 13 8 } { 1 3 6 1 5 5 13 9 } { 1 3 6 1 5 5 13 10 } { 1 3 6 1 5 5 13 11 } { 1 3 6 1 5 5 13 12 } { 1 3 6 1 5 5 13 13 } { 1 3 6 1 5 5 13 14 } { 1 3 6 1 5 5 13 15 } { 1 3 6 1 5 5 13 16 } { 1 3 6 1 5 5 13 17 } { 1 3 6 1 5 5 13 18 } { 1 3 6 1 5 5 13 19 } { 1 3 6 1 5 5 13 20 } { 1 3 6 1 5 5 13 21 } { 1 3 6 1 5 5 13 22 } { 1 3 6 1 5 5 13 23 } { 1 3 6 1 5 5 13 24 } { 1 3 6 1 5 5 13 25 } { 1 3 6 1 5 5 13 26 } { 1 3 6 1 5 5 13 27 } { 1 2 840 113554 1 2 2 5 18 } } { } { } [ 4753322d4b524235 ] [ 6b726235 ] [ Kerberos.5.GSS.A... ] } { { 1 3 6 1 5 2 5 } { { 1 2 840 113554 1 2 1 1 } { 1 2 840 113554 1 2 1 2 } { 1 2 840 113554 1 2 1 3 } { 1 2 840 113554 1 2 1 4 } { 1 3 6 1 5 6 2 } { 1 3 6 1 5 6 4 } { 1 2 840 113554 1 2 2 1 } { 1 3 6 1 5 6 6 } { 1 2 840 113554 1 2 2 2 } } { { 1 3 6 1 5 5 13 1 } { 1 3 6 1 5 5 13 9 } { 1 3 6 1 5 5 13 10 } { 1 3 6 1 5 5 13 11 } { 1 3 6 1 5 5 13 16 } { 1 3 6 1 5 5 13 17 } { 1 3 6 1 5 5 13 18 } { 1 3 6 1 5 5 13 19 } { 1 3 6 1 5 5 13 20 } { 1 3 6 1 5 5 13 21 } { 1 3 6 1 5 5 13 22 } { 1 3 6 1 5 5 13 23 } { 1 3 6 1 5 5 13 24 } { 1 3 6 1 5 5 13 27 } { 1 3 6 1 5 5 13 12 } { 1 3 6 1 5 5 13 8 } } { { 1 3 6 1 5 5 13 1 } { 1 3 6 1 5 5 13 2 } { 1 3 6 1 5 5 13 3 } { 1 3 6 1 5 5 13 4 } { 1 3 6 1 5 5 13 5 } { 1 3 6 1 5 5 13 6 } { 1 3 6 1 5 5 13 7 } { 1 3 6 1 5 5 13 8 } { 1 3 6 1 5 5 13 9 } { 1 3 6 1 5 5 13 10 } { 1 3 6 1 5 5 13 11 } { 1 3 6 1 5 5 13 12 } { 1 3 6 1 5 5 13 13 } { 1 3 6 1 5 5 13 14 } { 1 3 6 1 5 5 13 15 } { 1 3 6 1 5 5 13 16 } { 1 3 6 1 5 5 13 17 } { 1 3 6 1 5 5 13 18 } { 1 3 6 1 5 5 13 19 } { 1 3 6 1 5 5 13 20 } { 1 3 6 1 5 5 13 21 } { 1 3 6 1 5 5 13 22 } { 1 3 6 1 5 5 13 23 } { 1 3 6 1 5 5 13 24 } { 1 3 6 1 5 5 13 25 } { 1 3 6 1 5 5 13 26 } { 1 3 6 1 5 5 13 27 } { 1 2 840 113554 1 2 2 5 18 } } { } { } [ 4753322d49414b455242 ] [ 69616b657262 ] [ Initial.and.Pass... ] } { { 1 3 6 1 5 5 2 } { { 1 2 840 113554 1 2 1 1 } { 1 2 840 113554 1 2 1 2 } { 1 2 840 113554 1 2 1 3 } { 1 2 840 113554 1 2 1 4 } } { { 1 3 6 1 5 5 13 4 } { 1 3 6 1 5 5 13 9 } } { { 1 3 6 1 5 5 13 1 } { 1 3 6 1 5 5 13 2 } { 1 3 6 1 5 5 13 3 } { 1 3 6 1 5 5 13 4 } { 1 3 6 1 5 5 13 5 } { 1 3 6 1 5 5 13 6 } { 1 3 6 1 5 5 13 7 } { 1 3 6 1 5 5 13 8 } { 1 3 6 1 5 5 13 9 } { 1 3 6 1 5 5 13 10 } { 1 3 6 1 5 5 13 11 } { 1 3 6 1 5 5 13 12 } { 1 3 6 1 5 5 13 13 } { 1 3 6 1 5 5 13 14 } { 1 3 6 1 5 5 13 15 } { 1 3 6 1 5 5 13 16 } { 1 3 6 1 5 5 13 17 } { 1 3 6 1 5 5 13 18 } { 1 3 6 1 5 5 13 19 } { 1 3 6 1 5 5 13 20 } { 1 3 6 1 5 5 13 21 } { 1 3 6 1 5 5 13 22 } { 1 3 6 1 5 5 13 23 } { 1 3 6 1 5 5 13 24 } { 1 3 6 1 5 5 13 25 } { 1 3 6 1 5 5 13 26 } { 1 3 6 1 5 5 13 27 } { 1 2 840 113554 1 2 2 5 18 } } { } { } [ 53504e45474f ] [ 73706e65676f ] [ Simple.and.Prote... ] } ] mech_attr_descs: [ { { 1 3 6 1 5 5 13 1 } [ GSS.C.MA.MECH.CO... ] [ 636f6e63726574652d6d656368 ] [ Mechanism.is.nei... ] } { { 1 3 6 1 5 5 13 9 } [ GSS.C.MA.ITOK.FR... ] [ initial.is.frame... ] [ Mechanism.s.init... ] } { { 1 3 6 1 5 5 13 10 } [ GSS.C.MA.AUTH.IN... ] [ 617574682d696e69742d7072696e63 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 11 } [ GSS.C.MA.AUTH.TA... ] [ 617574682d746172672d7072696e63 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 16 } [ GSS.C.MA.DELEG.C... ] [ 64656c65672d63726564 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 17 } [ GSS.C.MA.INTEG.P... ] [ 696e7465672d70726f74 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 18 } [ GSS.C.MA.CONF.PR... ] [ 636f6e662d70726f74 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 19 } [ 4753535f435f4d415f4d4943 ] [ 6d6963 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 20 } [ 4753535f435f4d415f57524150 ] [ 77726170 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 21 } [ GSS.C.MA.PROT.RE... ] [ 70726f742d7265616479 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 22 } [ GSS.C.MA.REPLAY.... ] [ 7265706c61792d646574656374696f6e ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 23 } [ 4753535f435f4d415f4f4f535f444554 ] [ 6f6f732d646574656374696f6e ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 24 } [ GSS.C.MA.CBINDIN... ] [ 6368616e6e656c2d62696e64696e6773 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 27 } [ GSS.C.MA.CTX.TRA... ] [ 636f6e746578742d7472616e73666572 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 2 } [ GSS.C.MA.MECH.PS... ] [ 70736575646f2d6d656368 ] [ Mechanism.is.a.p... ] } { { 1 3 6 1 5 5 13 3 } [ GSS.C.MA.MECH.CO... ] [ 636f6d706f736974652d6d656368 ] [ Mechanism.is.a.c... ] } { { 1 3 6 1 5 5 13 4 } [ GSS.C.MA.MECH.NE... ] [ mech.negotiation... ] [ Mechanism.negoti... ] } { { 1 3 6 1 5 5 13 5 } [ GSS.C.MA.MECH.GL... ] [ 6d6563682d676c7565 ] [ OID.is.not.a.mec... ] } { { 1 3 6 1 5 5 13 6 } [ GSS.C.MA.NOT.MEC... ] [ 6e6f742d6d656368 ] [ Known.OID.but.no... ] } { { 1 3 6 1 5 5 13 7 } [ GSS.C.MA.DEPRECA... ] [ 6d6563682d64657072656361746564 ] [ Mechanism.is.dep... ] } { { 1 3 6 1 5 5 13 8 } [ GSS.C.MA.NOT.DFL... ] [ 6d6563682d6e6f742d64656661756c74 ] [ Mechanism.must.n... ] } { { 1 3 6 1 5 5 13 12 } [ GSS.C.MA.AUTH.IN... ] [ auth.init.princ.... ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 13 } [ GSS.C.MA.AUTH.TA... ] [ auth.target.prin... ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 14 } [ GSS.C.MA.AUTH.IN... ] [ auth.init.princ.... ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 15 } [ GSS.C.MA.AUTH.TA... ] [ auth.targ.princ.... ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 25 } [ 4753535f435f4d415f504653 ] [ 706673 ] [ Mechanism.suppor... ] } { { 1 3 6 1 5 5 13 26 } [ GSS.C.MA.COMPRES... ] [ 636f6d7072657373 ] [ Mechanism.suppor... ] } { { 1 2 840 113554 1 2 2 5 18 } [ GSS.C.MA.NEGOEX.... ] [ 6e65676f65782d6f6e6c79 ] [ NegoEx.mechanism... ] } ] supported_extensions: [ ] ) 249s [2024/03/24 06:38:47]: Total sent bytes: 6572 249s [CID 11][2024/03/24 06:38:47]: Connection matched service HTTP 249s [CID 11][2024/03/24 06:38:47]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 249s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .D................. ] 0 } add_cred: 0 desired_name: time_req: 4294967295 desired_mechs: { { 1 2 840 113554 1 2 2 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 ) 249s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .D................. ] 0 } ) 249s [2024/03/24 06:38:47]: Total received bytes: 2836 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [2024/03/24 06:38:47]: Total sent bytes: 7616 249s [2024/03/24 06:38:47]: Total received bytes: 4684 249s [2024/03/24 06:38:47]: Idle for: 0 seconds 249s [CID 11][2024/03/24 06:38:47]: Connection matched service HTTP 249s [CID 11][2024/03/24 06:38:47]: gp_rpc_execute: executing 9 (GSSX_ACCEPT_SEC_CONTEXT) for service "HTTP", euid: 33,socket: (null) 249s GSSX_ARG_ACCEPT_SEC_CONTEXT( call_ctx: { "" [ ] } context_handle: cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .D................. ] 0 } input_token: [ ........H.......... ] input_cb: ret_deleg_cred: 1 ) 249s GSSX_RES_ACCEPT_SEC_CONTEXT( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } context_handle: { [ ......H............ ] [ ] 0 { 1 2 840 113554 1 2 2 } "root@GSSPROXY.EXAMPLE.ORG" "HTTP/adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" 86699 438 0 1 } output_token: [ .......H........... ] delegated_cred_handle: ) 249s [2024/03/24 06:38:47]: Total sent bytes: 9908 249s [2024/03/24 06:38:48]: Client [2024/03/24 06:38:48]: (/usr/sbin/apache2) [2024/03/24 06:38:48]: connected (fd = 12)[2024/03/24 06:38:48]: (pid = 2448) (uid = 0) (gid = 0)[2024/03/24 06:38:48]: 249s [2024/03/24 06:38:48]: Total received bytes: 4688 249s [2024/03/24 06:38:48]: Idle for: 1 seconds 249s [CID 12][2024/03/24 06:38:48]: No matching service found 249s [2024/03/24 06:38:48]: Total received bytes: 4740 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [2024/03/24 06:38:48]: Client [2024/03/24 06:38:48]: (/usr/sbin/apache2) [2024/03/24 06:38:48]: connected (fd = 12)[2024/03/24 06:38:48]: (pid = 2448) (uid = 0) (gid = 0)[2024/03/24 06:38:48]: 249s [2024/03/24 06:38:48]: Total received bytes: 4744 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [CID 12][2024/03/24 06:38:48]: No matching service found 249s [2024/03/24 06:38:48]: Total received bytes: 4796 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [2024/03/24 06:38:48]: Client [2024/03/24 06:38:48]: (/usr/sbin/apache2) [2024/03/24 06:38:48]: connected (fd = 12)[2024/03/24 06:38:48]: (pid = 2448) (uid = 0) (gid = 0)[2024/03/24 06:38:48]: 249s [2024/03/24 06:38:48]: Total received bytes: 4800 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [CID 12][2024/03/24 06:38:48]: No matching service found 249s [2024/03/24 06:38:48]: Total received bytes: 4852 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [2024/03/24 06:38:48]: Client [2024/03/24 06:38:48]: (/usr/sbin/apache2) [2024/03/24 06:38:48]: connected (fd = 12)[2024/03/24 06:38:48]: (pid = 2448) (uid = 0) (gid = 0)[2024/03/24 06:38:48]: 249s [2024/03/24 06:38:48]: Total received bytes: 4856 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [CID 12][2024/03/24 06:38:48]: No matching service found 249s [2024/03/24 06:38:48]: Total received bytes: 4908 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s [2024/03/24 06:38:48]: Client [2024/03/24 06:38:48]: (/usr/sbin/apache2) [2024/03/24 06:38:48]: connected (fd = 12)[2024/03/24 06:38:48]: (pid = 2448) (uid = 0) (gid = 0)[2024/03/24 06:38:48]: 249s [CID 12][2024/03/24 06:38:48]: No matching service found 249s [2024/03/24 06:38:48]: Total received bytes: 4964 249s [2024/03/24 06:38:48]: Idle for: 0 seconds 249s ----- BEGIN FILE /tmp/out-gssproxy-out ----- 249s ==> /var/log/apache2/error.log <== 249s [Sun Mar 24 06:38:37.055595 2024] [mpm_event:notice] [pid 1710:tid 4396021667712] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 249s [Sun Mar 24 06:38:37.055658 2024] [core:notice] [pid 1710:tid 4396021667712] AH00094: Command line: '/usr/sbin/apache2' 249s [Sun Mar 24 06:38:44.858615 2024] [mpm_event:notice] [pid 1710:tid 4396021667712] AH00492: caught SIGWINCH, shutting down gracefully 249s [Sun Mar 24 06:38:47.557378 2024] [mpm_event:notice] [pid 2377:tid 4396520789888] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 249s [Sun Mar 24 06:38:47.557437 2024] [core:notice] [pid 2377:tid 4396520789888] AH00094: Command line: '/usr/sbin/apache2' 249s [Sun Mar 24 06:38:48.157683 2024] [mpm_event:notice] [pid 2377:tid 4396520789888] AH00492: caught SIGWINCH, shutting down gracefully 249s 249s ==> /var/log/apache2/access.log <== 249s 127.0.0.1 - root@GSSPROXY.EXAMPLE.ORG [24/Mar/2024:06:38:47 +0000] "GET /gssproxy-autopkgtest/foo HTTP/1.1" 200 491 "-" "curl/8.5.0" 249s + echo ----- BEGIN FILE /tmp/out-gssproxy-out ----- 249s + cat /tmp/out-gssproxy-out 249s + tail -v /var/log/apache2/error.log /var/log/apache2/access.log 249s + mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 249s + mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 249s + mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 249s + rm /etc/gssproxy/80-gssproxy-autopkgtest.conf /etc/apache2/conf-enabled/gssproxy-autopkgtest.conf /etc/systemd/system/apache2.service.d/gssproxy-autopkgtest.conf /var/www/html/gssproxy-autopkgtest/foo /var/lib/krb5kdc/gssproxy-principal /var/lib/krb5kdc/gssproxy-principal.kadm5 /var/lib/krb5kdc/gssproxy-principal.kadm5.lock /var/lib/krb5kdc/gssproxy-principal.ok /var/lib/krb5kdc/gssproxy-stash /etc/gssproxy/httpd.keytab 249s rm: cannot remove '/var/lib/krb5kdc/gssproxy-stash': No such file or directory 249s + test -d /etc/systemd/system/apache2.service.d 249s + rmdir /etc/systemd/system/apache2.service.d 249s + rmdir /var/www/html/gssproxy-autopkgtest 249s /tmp/autopkgtest.6IH21s/wrapper.sh: Killing leaked background processes: 2285 249s PID TTY STAT TIME COMMAND 249s 2285 ? S 0:00 tail --pid=2253 -F /var/log/syslog 249s autopkgtest [06:38:50]: test gssproxy-apache: -----------------------] 250s autopkgtest [06:38:51]: test gssproxy-apache: - - - - - - - - - - results - - - - - - - - - - 250s gssproxy-apache PASS 250s autopkgtest [06:38:51]: @@@@@@@@@@@@@@@@@@@@ summary 250s gssproxy-apache PASS 261s Creating nova instance adt-noble-s390x-gssproxy-20240324-063441-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240324.img (UUID e13d5247-1289-46d4-b3a9-3aa9fa45fdc9)...