0s autopkgtest [04:37:24]: starting date and time: 2024-03-20 04:37:24+0000 0s autopkgtest [04:37:24]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [04:37:24]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.zy6glddw/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:libselinux,src:ruby-defaults --apt-upgrade gssproxy --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libselinux/3.5-2ubuntu1 ruby-defaults/1:3.2~ubuntu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-6.secgroup --name adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-arm64-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/ 140s autopkgtest [04:39:44]: testbed dpkg architecture: arm64 141s autopkgtest [04:39:45]: testbed apt version: 2.7.12 141s autopkgtest [04:39:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 141s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 141s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 142s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 142s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3808 kB] 142s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.7 kB] 142s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [671 kB] 142s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 142s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [41.7 kB] 142s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 142s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4217 kB] 142s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 142s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [56.7 kB] 142s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 144s Fetched 9478 kB in 2s (5812 kB/s) 144s Reading package lists... 146s Reading package lists... 147s Building dependency tree... 147s Reading state information... 147s Calculating upgrade... 147s The following packages will be upgraded: 147s libselinux1 ubuntu-minimal ubuntu-standard 147s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 101 kB of archives. 147s After this operation, 0 B of additional disk space will be used. 147s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libselinux1 arm64 3.5-2ubuntu1 [79.8 kB] 147s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-minimal arm64 1.536build1 [10.7 kB] 147s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-standard arm64 1.536build1 [10.7 kB] 148s Fetched 101 kB in 0s (274 kB/s) 148s (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 ... 74758 files and directories currently installed.) 148s Preparing to unpack .../libselinux1_3.5-2ubuntu1_arm64.deb ... 148s Unpacking libselinux1:arm64 (3.5-2ubuntu1) over (3.5-2build1) ... 148s Setting up libselinux1:arm64 (3.5-2ubuntu1) ... 148s (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 ... 74758 files and directories currently installed.) 148s Preparing to unpack .../ubuntu-minimal_1.536build1_arm64.deb ... 148s Unpacking ubuntu-minimal (1.536build1) over (1.536) ... 148s Preparing to unpack .../ubuntu-standard_1.536build1_arm64.deb ... 148s Unpacking ubuntu-standard (1.536build1) over (1.536) ... 148s Setting up ubuntu-minimal (1.536build1) ... 148s Setting up ubuntu-standard (1.536build1) ... 148s Processing triggers for libc-bin (2.39-0ubuntu2) ... 149s Reading package lists... 149s Building dependency tree... 149s Reading state information... 149s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 150s sh: Attempting to set up Debian/Ubuntu apt sources automatically 150s sh: Distribution appears to be Ubuntu 151s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s eatmydata is already the newest version (131-1). 151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 152s Reading package lists... 152s Building dependency tree... 152s Reading state information... 152s dbus is already the newest version (1.14.10-4ubuntu1). 152s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 152s Reading package lists... 152s Building dependency tree... 152s Reading state information... 153s rng-tools-debian is already the newest version (2.4). 153s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 153s Reading package lists... 153s Building dependency tree... 153s Reading state information... 153s The following packages will be REMOVED: 153s cloud-init* python3-configobj* python3-debconf* 154s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 154s After this operation, 3252 kB disk space will be freed. 154s (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 ... 74758 files and directories currently installed.) 154s Removing cloud-init (24.1.1-0ubuntu1) ... 154s Removing python3-configobj (5.0.8-3) ... 154s Removing python3-debconf (1.5.86) ... 154s Processing triggers for man-db (2.12.0-3) ... 155s (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 ... 74369 files and directories currently installed.) 155s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 155s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 155s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 155s invoke-rc.d: policy-rc.d denied execution of try-restart. 156s Reading package lists... 156s Building dependency tree... 156s Reading state information... 156s linux-generic is already the newest version (6.8.0-11.11+1). 156s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 157s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 157s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 157s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 159s Reading package lists... 159s Reading package lists... 159s Building dependency tree... 159s Reading state information... 159s Calculating upgrade... 159s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 160s Reading package lists... 160s Building dependency tree... 160s Reading state information... 160s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 161s autopkgtest [04:40:05]: rebooting testbed after setup commands that affected boot 188s autopkgtest [04:40:32]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 190s autopkgtest [04:40:34]: @@@@@@@@@@@@@@@@@@@@ apt-source gssproxy 193s Get:1 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (dsc) [1699 B] 193s Get:2 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (tar) [190 kB] 193s Get:3 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (diff) [6864 B] 194s gpgv: Signature made Fri Jan 12 10:48:47 2024 UTC 194s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 194s gpgv: issuer "simon@josefsson.org" 194s gpgv: Can't check signature: No public key 194s dpkg-source: warning: cannot verify inline signature for ./gssproxy_0.9.2-2.dsc: no acceptable signature found 194s autopkgtest [04:40:38]: testing package gssproxy version 0.9.2-2 194s autopkgtest [04:40:38]: build not needed 194s autopkgtest [04:40:38]: test gssproxy-apache: preparing testbed 201s Reading package lists... 201s Building dependency tree... 201s Reading state information... 201s Starting pkgProblemResolver with broken count: 0 201s Starting 2 pkgProblemResolver with broken count: 0 201s Done 202s The following additional packages will be installed: 202s apache2 apache2-bin apache2-data apache2-utils gssproxy krb5-admin-server 202s krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi libapr1 202s libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libbasicobjects0 202s libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 libkadm5clnt-mit12 202s libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 libref-array1 202s libverto-libevent1 libverto1 202s Suggested packages: 202s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 202s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 202s Recommended packages: 202s ssl-cert 202s The following NEW packages will be installed: 202s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep gssproxy 202s krb5-admin-server krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi 202s libapr1 libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap 202s libbasicobjects0 libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 202s libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 202s libref-array1 libverto-libevent1 libverto1 202s 0 upgraded, 28 newly installed, 0 to remove and 0 not upgraded. 202s Need to get 3059 kB/3059 kB of archives. 202s After this operation, 18.5 MB of additional disk space will be used. 202s Get:1 /tmp/autopkgtest.Bm9ajX/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [756 B] 202s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libapr1 arm64 1.7.2-3 [103 kB] 202s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1 arm64 1.6.3-1ubuntu1 [91.7 kB] 202s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1-dbd-sqlite3 arm64 1.6.3-1ubuntu1 [11.0 kB] 202s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1-ldap arm64 1.6.3-1ubuntu1 [8972 B] 202s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 liblua5.4-0 arm64 5.4.6-3 [158 kB] 202s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-bin arm64 2.4.58-1ubuntu2 [1316 kB] 203s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-data all 2.4.58-1ubuntu2 [163 kB] 203s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-utils arm64 2.4.58-1ubuntu2 [94.9 kB] 203s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 apache2 arm64 2.4.58-1ubuntu2 [90.2 kB] 203s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 krb5-config all 2.7 [22.0 kB] 203s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 libgssrpc4 arm64 1.20.1-5build1 [57.4 kB] 203s Get:13 http://ftpmaster.internal/ubuntu noble/main arm64 libkadm5clnt-mit12 arm64 1.20.1-5build1 [39.9 kB] 203s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 libkdb5-10 arm64 1.20.1-5build1 [39.8 kB] 203s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 libkadm5srv-mit12 arm64 1.20.1-5build1 [53.2 kB] 203s Get:16 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-user arm64 1.20.1-5build1 [108 kB] 203s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 libevent-2.1-7 arm64 2.1.12-stable-9 [138 kB] 203s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libverto1 arm64 0.3.1-1ubuntu5 [10.2 kB] 203s Get:19 http://ftpmaster.internal/ubuntu noble/main arm64 libverto-libevent1 arm64 0.3.1-1ubuntu5 [5848 B] 203s Get:20 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-kdc arm64 1.20.1-5build1 [180 kB] 203s Get:21 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-admin-server arm64 1.20.1-5build1 [94.6 kB] 203s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 libbasicobjects0 arm64 0.6.2-2 [5586 B] 203s Get:23 http://ftpmaster.internal/ubuntu noble/main arm64 libcollection4 arm64 0.6.2-2 [23.0 kB] 203s Get:24 http://ftpmaster.internal/ubuntu noble/main arm64 libpath-utils1 arm64 0.6.2-2 [8722 B] 203s Get:25 http://ftpmaster.internal/ubuntu noble/main arm64 libref-array1 arm64 0.6.2-2 [7042 B] 203s Get:26 http://ftpmaster.internal/ubuntu noble/main arm64 libini-config5 arm64 0.6.2-2 [43.7 kB] 203s Get:27 http://ftpmaster.internal/ubuntu noble/universe arm64 gssproxy arm64 0.9.2-2 [124 kB] 203s Get:28 http://ftpmaster.internal/ubuntu noble/universe arm64 libapache2-mod-auth-gssapi arm64 1.6.4-3 [60.8 kB] 203s Preconfiguring packages ... 204s Fetched 3059 kB in 1s (2645 kB/s) 204s Selecting previously unselected package libapr1:arm64. 204s (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 ... 74314 files and directories currently installed.) 204s Preparing to unpack .../00-libapr1_1.7.2-3_arm64.deb ... 204s Unpacking libapr1:arm64 (1.7.2-3) ... 204s Selecting previously unselected package libaprutil1:arm64. 204s Preparing to unpack .../01-libaprutil1_1.6.3-1ubuntu1_arm64.deb ... 204s Unpacking libaprutil1:arm64 (1.6.3-1ubuntu1) ... 204s Selecting previously unselected package libaprutil1-dbd-sqlite3:arm64. 204s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-1ubuntu1_arm64.deb ... 204s Unpacking libaprutil1-dbd-sqlite3:arm64 (1.6.3-1ubuntu1) ... 204s Selecting previously unselected package libaprutil1-ldap:arm64. 204s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-1ubuntu1_arm64.deb ... 204s Unpacking libaprutil1-ldap:arm64 (1.6.3-1ubuntu1) ... 204s Selecting previously unselected package liblua5.4-0:arm64. 204s Preparing to unpack .../04-liblua5.4-0_5.4.6-3_arm64.deb ... 204s Unpacking liblua5.4-0:arm64 (5.4.6-3) ... 204s Selecting previously unselected package apache2-bin. 204s Preparing to unpack .../05-apache2-bin_2.4.58-1ubuntu2_arm64.deb ... 204s Unpacking apache2-bin (2.4.58-1ubuntu2) ... 205s Selecting previously unselected package apache2-data. 205s Preparing to unpack .../06-apache2-data_2.4.58-1ubuntu2_all.deb ... 205s Unpacking apache2-data (2.4.58-1ubuntu2) ... 205s Selecting previously unselected package apache2-utils. 205s Preparing to unpack .../07-apache2-utils_2.4.58-1ubuntu2_arm64.deb ... 205s Unpacking apache2-utils (2.4.58-1ubuntu2) ... 205s Selecting previously unselected package apache2. 205s Preparing to unpack .../08-apache2_2.4.58-1ubuntu2_arm64.deb ... 205s Unpacking apache2 (2.4.58-1ubuntu2) ... 205s Selecting previously unselected package krb5-config. 205s Preparing to unpack .../09-krb5-config_2.7_all.deb ... 205s Unpacking krb5-config (2.7) ... 205s Selecting previously unselected package libgssrpc4:arm64. 205s Preparing to unpack .../10-libgssrpc4_1.20.1-5build1_arm64.deb ... 205s Unpacking libgssrpc4:arm64 (1.20.1-5build1) ... 205s Selecting previously unselected package libkadm5clnt-mit12:arm64. 205s Preparing to unpack .../11-libkadm5clnt-mit12_1.20.1-5build1_arm64.deb ... 205s Unpacking libkadm5clnt-mit12:arm64 (1.20.1-5build1) ... 205s Selecting previously unselected package libkdb5-10:arm64. 205s Preparing to unpack .../12-libkdb5-10_1.20.1-5build1_arm64.deb ... 205s Unpacking libkdb5-10:arm64 (1.20.1-5build1) ... 205s Selecting previously unselected package libkadm5srv-mit12:arm64. 205s Preparing to unpack .../13-libkadm5srv-mit12_1.20.1-5build1_arm64.deb ... 205s Unpacking libkadm5srv-mit12:arm64 (1.20.1-5build1) ... 205s Selecting previously unselected package krb5-user. 205s Preparing to unpack .../14-krb5-user_1.20.1-5build1_arm64.deb ... 205s Unpacking krb5-user (1.20.1-5build1) ... 205s Selecting previously unselected package libevent-2.1-7:arm64. 205s Preparing to unpack .../15-libevent-2.1-7_2.1.12-stable-9_arm64.deb ... 205s Unpacking libevent-2.1-7:arm64 (2.1.12-stable-9) ... 205s Selecting previously unselected package libverto1:arm64. 205s Preparing to unpack .../16-libverto1_0.3.1-1ubuntu5_arm64.deb ... 205s Unpacking libverto1:arm64 (0.3.1-1ubuntu5) ... 205s Selecting previously unselected package libverto-libevent1:arm64. 205s Preparing to unpack .../17-libverto-libevent1_0.3.1-1ubuntu5_arm64.deb ... 205s Unpacking libverto-libevent1:arm64 (0.3.1-1ubuntu5) ... 205s Selecting previously unselected package krb5-kdc. 205s Preparing to unpack .../18-krb5-kdc_1.20.1-5build1_arm64.deb ... 205s Unpacking krb5-kdc (1.20.1-5build1) ... 205s Selecting previously unselected package krb5-admin-server. 205s Preparing to unpack .../19-krb5-admin-server_1.20.1-5build1_arm64.deb ... 205s Unpacking krb5-admin-server (1.20.1-5build1) ... 205s Selecting previously unselected package libbasicobjects0:arm64. 205s Preparing to unpack .../20-libbasicobjects0_0.6.2-2_arm64.deb ... 205s Unpacking libbasicobjects0:arm64 (0.6.2-2) ... 205s Selecting previously unselected package libcollection4:arm64. 205s Preparing to unpack .../21-libcollection4_0.6.2-2_arm64.deb ... 205s Unpacking libcollection4:arm64 (0.6.2-2) ... 205s Selecting previously unselected package libpath-utils1:arm64. 205s Preparing to unpack .../22-libpath-utils1_0.6.2-2_arm64.deb ... 205s Unpacking libpath-utils1:arm64 (0.6.2-2) ... 205s Selecting previously unselected package libref-array1:arm64. 205s Preparing to unpack .../23-libref-array1_0.6.2-2_arm64.deb ... 205s Unpacking libref-array1:arm64 (0.6.2-2) ... 205s Selecting previously unselected package libini-config5:arm64. 205s Preparing to unpack .../24-libini-config5_0.6.2-2_arm64.deb ... 205s Unpacking libini-config5:arm64 (0.6.2-2) ... 205s Selecting previously unselected package gssproxy. 205s Preparing to unpack .../25-gssproxy_0.9.2-2_arm64.deb ... 205s Unpacking gssproxy (0.9.2-2) ... 205s Selecting previously unselected package libapache2-mod-auth-gssapi. 205s Preparing to unpack .../26-libapache2-mod-auth-gssapi_1.6.4-3_arm64.deb ... 205s Unpacking libapache2-mod-auth-gssapi (1.6.4-3) ... 205s Selecting previously unselected package autopkgtest-satdep. 205s Preparing to unpack .../27-1-autopkgtest-satdep.deb ... 205s Unpacking autopkgtest-satdep (0) ... 206s Setting up libpath-utils1:arm64 (0.6.2-2) ... 206s Setting up libbasicobjects0:arm64 (0.6.2-2) ... 206s Setting up libapr1:arm64 (1.7.2-3) ... 206s Setting up libgssrpc4:arm64 (1.20.1-5build1) ... 206s Setting up krb5-config (2.7) ... 206s Setting up libevent-2.1-7:arm64 (2.1.12-stable-9) ... 206s Setting up libcollection4:arm64 (0.6.2-2) ... 206s Setting up liblua5.4-0:arm64 (5.4.6-3) ... 206s Setting up libref-array1:arm64 (0.6.2-2) ... 206s Setting up apache2-data (2.4.58-1ubuntu2) ... 206s Setting up libkadm5clnt-mit12:arm64 (1.20.1-5build1) ... 206s Setting up libaprutil1:arm64 (1.6.3-1ubuntu1) ... 206s Setting up libkdb5-10:arm64 (1.20.1-5build1) ... 206s Setting up libini-config5:arm64 (0.6.2-2) ... 206s Setting up libaprutil1-ldap:arm64 (1.6.3-1ubuntu1) ... 206s Setting up libaprutil1-dbd-sqlite3:arm64 (1.6.3-1ubuntu1) ... 206s Setting up libkadm5srv-mit12:arm64 (1.20.1-5build1) ... 206s Setting up apache2-utils (2.4.58-1ubuntu2) ... 206s Setting up apache2-bin (2.4.58-1ubuntu2) ... 206s Setting up krb5-user (1.20.1-5build1) ... 206s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 206s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 206s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 206s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 206s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 206s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 206s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 206s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 206s Setting up libapache2-mod-auth-gssapi (1.6.4-3) ... 206s Package apache2 is not configured yet. Will defer actions by package libapache2-mod-auth-gssapi. 206s Setting up apache2 (2.4.58-1ubuntu2) ... 206s Enabling module mpm_event. 206s Enabling module authz_core. 206s Enabling module authz_host. 206s Enabling module authn_core. 206s Enabling module auth_basic. 206s Enabling module access_compat. 206s Enabling module authn_file. 206s Enabling module authz_user. 206s Enabling module alias. 206s Enabling module dir. 206s Enabling module autoindex. 206s Enabling module env. 206s Enabling module mime. 206s Enabling module negotiation. 206s Enabling module setenvif. 206s Enabling module filter. 206s Enabling module deflate. 206s Enabling module status. 206s Enabling module reqtimeout. 206s Enabling conf charset. 207s Enabling conf localized-error-pages. 207s Enabling conf other-vhosts-access-log. 207s Enabling conf security. 207s Enabling conf serve-cgi-bin. 207s Enabling site 000-default. 207s info: Executing deferred 'a2enmod auth_gssapi' for package libapache2-mod-auth-gssapi 207s Enabling module auth_gssapi. 207s Created symlink /etc/systemd/system/multi-user.target.wants/apache2.service → /usr/lib/systemd/system/apache2.service. 208s Created symlink /etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service → /usr/lib/systemd/system/apache-htcacheclean.service. 209s Setting up libverto-libevent1:arm64 (0.3.1-1ubuntu5) ... 209s Setting up libverto1:arm64 (0.3.1-1ubuntu5) ... 209s Setting up gssproxy (0.9.2-2) ... 209s Created symlink /etc/systemd/user/sockets.target.wants/gssuserproxy.socket → /usr/lib/systemd/user/gssuserproxy.socket. 209s Created symlink /etc/systemd/system/multi-user.target.wants/gssproxy.service → /usr/lib/systemd/system/gssproxy.service. 209s Setting up krb5-kdc (1.20.1-5build1) ... 210s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service. 210s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 211s Setting up krb5-admin-server (1.20.1-5build1) ... 211s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service. 211s Setting up autopkgtest-satdep (0) ... 211s Processing triggers for libc-bin (2.39-0ubuntu2) ... 211s Processing triggers for ufw (0.36.2-5) ... 211s Processing triggers for man-db (2.12.0-3) ... 216s (Reading database ... 75222 files and directories currently installed.) 216s Removing autopkgtest-satdep (0) ... 216s autopkgtest [04:41:00]: test gssproxy-apache: [----------------------- 217s + command -v ss 217s + command -v id 217s + command -v hostname 217s + command -v dpkg 217s + dpkg -V krb5-kdc 217s + dpkg -V krb5-admin-server 217s + dpkg -V apache2 217s + dpkg -V libapache2-mod-auth-gssapi 217s + dpkg -V gssproxy 217s + dpkg -V curl 217s + service apache2 stop 217s /usr/bin/ss 217s /usr/bin/id 217s /usr/bin/hostname 217s /usr/bin/dpkg 218s + service krb5-kdc stop 218s + command -v systemctl 218s + service gssproxy/usr/bin/systemctl 218s stop 219s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 219s + trap 219s set +e; 219s kdestroy 219s service apache2 stop 219s service krb5-kdc stop 219s for f in /tmp/out-*; 219s do echo ----- BEGIN FILE $f ----- 219s cat $f 219s done 219s tail -v /var/log/apache2/error.log /var/log/apache2/access.log 219s mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 219s mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 219s mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 219s 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 219s test -d /etc/systemd/system/apache2.service.d && rmdir /etc/systemd/system/apache2.service.d 219s rmdir /var/www/html/gssproxy-autopkgtest 219s 0 INT QUIT ABRT PIPE TERM 219s + tail --pid=2335 -F /var/log/syslog 219s + id -un 219s + my_user=root 219s + hostname -f 219s + my_hostname=adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal 219s + hostname -d 219s + my_domainname=novalocal 219s + test -f /etc/krb5kdc/kdc.conf-gssproxysave 219s + cp /etc/krb5kdc/kdc.conf /etc/krb5kdc/kdc.conf-gssproxysave 219s + cat 219s + test -f /etc/krb5.conf-gssproxysave 219s + cp /etc/krb5.conf /etc/krb5.conf-gssproxysave 219s + cat 219s + kdb5_util -P foo create -s 219s 2024-03-20T04:40:55.690648+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo (kadmind)[2198]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 219s 2024-03-20T04:40:55.691376+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo kadmind[2198]: kadmind: Cannot open DB2 database '/var/lib/krb5kdc/principal': No such file or directory while initializing, aborting 219s 2024-03-20T04:40:55.693605+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Main process exited, code=exited, status=1/FAILURE 219s 2024-03-20T04:40:55.693897+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Failed with result 'exit-code'. 219s 2024-03-20T04:41:01.974070+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 219s 2024-03-20T04:41:02.212537+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 219s 2024-03-20T04:41:02.212972+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 219s 2024-03-20T04:41:03.090886+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopping gssproxy.service - GSSAPI Proxy Daemon... 219s 2024-03-20T04:41:03.113091+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: gssproxy.service: Deactivated successfully. 219s 2024-03-20T04:41:03.113311+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopped gssproxy.service - GSSAPI Proxy Daemon. 219s + Initializing database '/var/lib/krb5kdc/gssproxy-principal' for realm 'GSSPROXY.EXAMPLE.ORG', 219s master key name 'K/M@GSSPROXY.EXAMPLE.ORG' 219s kadmin.local addprinc -randkey HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal 219s + kadmin.local addprinc -pw bar root 219s + kadmin.local ktadd -k /etc/gssproxy/httpd.keytab HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal 219s Entry for principal HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 219s Entry for principal HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 219s + service krb5-kdc start 219s 2024-03-20T04:41:03.706963+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 219s 2024-03-20T04:41:03.721827+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo (krb5kdc)[2385]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 219s + i=0 219s + ss -na 219s + grep 0.0.0.0:17643 219s + grep LISTEN 219s 2024-03-20T04:41:03.743899+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center. 219s tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 219s + kdestroy 219s kdestroy: No credentials cache found while destroying cache 219s + echo bar 219s + kinit root 220s Password for root@GSSPROXY.EXAMPLE.ORG: 220s + test -f /etc/gssproxy/gssproxy.conf-gssproxysave 220s + cp /etc/gssproxy/gssproxy.conf /etc/gssproxy/gssproxy.conf-gssproxysave 220s + cat 220s + cat 220s + command -v systemctl 220s + cat 220s + KRB5RCACHEDIR=/var/lib/gssproxy/rcache /usr/sbin/gssproxy -i --debug-level=2 220s /usr/bin/systemctl 220s + /usr/bin/systemctl 220s 2024-03-20T04:41:04.602711+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Reloading requested from client PID 2412 ('systemctl') (unit session-1.scope)... 220s 2024-03-20T04:41:04.602815+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Reloading... 220s command -v systemctl 220s + mkdir -p /etc/systemd/system/apache2.service.d 220s + cat 220s + systemctl daemon-reload 220s 2024-03-20T04:41:04.870863+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Reloading finished in 271 ms. 220s + service apache2 start 221s 2024-03-20T04:41:05.471174+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Starting apache2.service - The Apache HTTP Server... 221s 2024-03-20T04:41:05.491067+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Started apache2.service - The Apache HTTP Server. 221s + mkdir -p /var/www/html/gssproxy-autopkgtest 221s + echo Ossifrage 221s + curl --verbose --negotiate -u : http://adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal/gssproxy-autopkgtest/foo 221s + grep Ossifrage /tmp/out-curl-out 221s Ossifrage 221s + echo PASS: /tmp/autopkgtest.Bm9ajX/build.AXd/src/debian/tests/gssproxy-apache 221s + exit 0 221s + set +e 221s + kdestroy 221s PASS: /tmp/autopkgtest.Bm9ajX/build.AXd/src/debian/tests/gssproxy-apache 221s + service apache2 stop 222s 2024-03-20T04:41:06.191765+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 222s 2024-03-20T04:41:06.230709+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 222s 2024-03-20T04:41:06.231501+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 222s + service krb5-kdc stop 222s 2024-03-20T04:41:06.663782+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopping krb5-kdc.service - Kerberos 5 Key Distribution Center... 222s 2024-03-20T04:41:06.664127+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: krb5-kdc.service: Deactivated successfully. 222s 2024-03-20T04:41:06.664629+00:00 adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo systemd[1]: Stopped krb5-kdc.service - Kerberos 5 Key Distribution Center. 222s ----- BEGIN FILE /tmp/out-curl-err ----- 222s + echo ----- BEGIN FILE /tmp/out-curl-err ----- 222s + cat /tmp/out-curl-err 222s * 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' 222s % Total % Received % Xferd Average Speed Time Time Time Current 222s Dload Upload Total Spent Left Speed 222s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0* Host adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal:80 was resolved. 222s * IPv6: (none) 222s * IPv4: 127.0.1.1 222s * Trying 127.0.1.1:80... 222s * Connected to adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal (127.0.1.1) port 80 222s * Server auth using Negotiate with user '' 222s > GET /gssproxy-autopkgtest/foo HTTP/1.1 222s > Host: adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal 222s > Authorization: Negotiate YIIDTgYGKwYBBQUCoIIDQjCCAz6gDTALBgkqhkiG9xIBAgKiggMrBIIDJ2CCAyMGCSqGSIb3EgECAgEAboIDEjCCAw6gAwIBBaEDAgEOogcDBQAgAAAAo4ICHmGCAhowggIWoAMCAQWhFhsUR1NTUFJPWFkuRVhBTVBMRS5PUkeiXDBaoAMCAQOhUzBRGwRIVFRQG0lhZHQtbm9ibGUtYXJtNjQtZ3NzcHJveHktMjAyNDAzMjAtMDQzNzI0LWp1anUtN2YyMjc1LXByb2QtcHJvcG8ubm92YWxvY2Fso4IBlzCCAZOgAwIBEqEDAgECooIBhQSCAYEDEn5hyRquy5Fy1HPzIrDmtVlPuem27Pi8RXEiZTfzfjjl+OMWmNdg5+OMmqpJwkKCnB8AaVCU8lXUptrlJzS4Dj72xPnsWkoVqaug3LPo4DN6YiOdiZO5DVAfDnGD6dm2FakYkFUjg1DtCGnh+iI8nnx6OV5gKLtSEYXwm6bqNbSskvubsGMDANFc04mRTdnTprUsUZ5FIpcios/2H32l28TEWtnFjKovpu2UBe4l6JkTQtLx6obpwjHvoAhV7DmurPkeoxAwuELg55e7AlUGpNCqaN5iZ4ivHihvrZwG2pFU6kNxOttfohhMjx3FvCgOTDbzxe0cJjB1C9N0a5PgK88vDmBIEeQhPv0YyE1RxKcZA0KTQ0ePRl6RcF92xpA/k56Ks7SCF3jSJ42fIl80R6kK6c7GhNS8RnkcILtKFIhiKuMAgDclrHVAgDjLRsNVbnVQ5iWs2up6RJjhvdThxmo1nbkwLj+t43rfdtvr7Y1AkqyZ5ELqoeeJez0iRf/3pIHWMIHToAMCARKigcsEgciRwLSk4oGT5DAqWJbbOM7uwyCKmNJUwIi68r9IxFJ9R5hj5TfgFd0uR9hP7UGRJhksQw09WXNCG6a9jXznw+djI/QTKzTWKBOs97ZqWdGaXbFkK/w5caGI+2kpPeg5f8rJUCE0QyY74KaJSVl6pOQuGGwiB9Qy4/ayAexI+x7HYfGDtp2L2gf5SpYoj+WjLZHACWwXKzfEMxi9TYpQZO97lemz+dNMrVdVmUiczZLqsflbUqUFwmHoCcr2DpSwL0kelw21rOohGg== 222s > User-Agent: curl/8.5.0 222s > Accept: */* 222s > 222s < HTTP/1.1 200 OK 222s < Date: Wed, 20 Mar 2024 04:41:05 GMT 222s < Server: Apache/2.4.58 (Ubuntu) 222s < WWW-Authenticate: Negotiate oYG3MIG0oAMKAQChCwYJKoZIhvcSAQICooGfBIGcYIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvdY9qz/uzarKBLwwQk4V4oGC1AxJ+rbF/WHil8PrWjUtpx6w61LvAvUDp+D15Vm0EReQ+exKlF/ovSm4KGYq6GCszAg76Fg7v9fiJkLmuu523a8HUvhfUjxeptfyEMLrUi1mhAN6eqIkcrACaLzVz 222s < Last-Modified: Wed, 20 Mar 2024 04:41:05 GMT 222s < ETag: W/"a-6141032dabd69" 222s < Accept-Ranges: bytes 222s < Content-Length: 10 222s < 222s { [10 bytes data] 222s 100 10 100 10 0 0 363 0 --:--:-- --:--:-- --:--:-- 370 222s * Connection #0 to host adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal left intact 222s ----- BEGIN FILE /tmp/out-curl-out ----- 222s + echo ----- BEGIN FILE /tmp/out-curl-out ----- 222s + cat /tmp/out-curl-out 222s Ossifrage 222s ----- BEGIN FILE /tmp/out-gssproxy-err ----- 222s + echo ----- BEGIN FILE /tmp/out-gssproxy-err ----- 222s + cat /tmp/out-gssproxy-err 222s [2024/03/20 04:41:04]: Debug Level changed to 2 222s [2024/03/20 04:41:04]: Debug Level changed to 2 222s [2024/03/20 04:41:04]: Keytab /etc/krb5.keytab has no content (-1765328203) 222s [2024/03/20 04:41:04]: Service: nfs-server, Enckey: [ephemeral], Enctype: 18 222s [2024/03/20 04:41:04]: Service: HTTP, Keytab: /etc/gssproxy/httpd.keytab, Enctype: 18 222s [2024/03/20 04:41:04]: Kernel doesn't support GSS-Proxy (can't open /proc/net/rpc/use-gss-proxy: 2 (No such file or directory)) 222s [2024/03/20 04:41:04]: Initialization complete. 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 56 222s [2024/03/20 04:41:05]: Idle for: 1710909665 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Total received bytes: 112 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 168 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 224 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 280 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 336 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 392 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 448 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2457) (uid = 0) (gid = 0)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 504 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: No matching service found 222s [2024/03/20 04:41:05]: Client [2024/03/20 04:41:05]: (/usr/sbin/apache2) [2024/03/20 04:41:05]: connected (fd = 11)[2024/03/20 04:41:05]: (pid = 2460) (uid = 33) (gid = 33)[2024/03/20 04:41:05]: 222s [2024/03/20 04:41:05]: Total received bytes: 508 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [2024/03/20 04:41:05]: Total received bytes: 624 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: Connection matched service HTTP 222s [CID 11][2024/03/20 04:41:05]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 222s 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 ) 222s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .......y........... ] 0 } ) 222s [2024/03/20 04:41:05]: Total sent bytes: 1044 222s [2024/03/20 04:41:05]: Total received bytes: 628 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [2024/03/20 04:41:05]: Total received bytes: 1700 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: Connection matched service HTTP 222s [CID 11][2024/03/20 04:41:05]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 222s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .......y........... ] 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 ) 222s GSSX_RES_ACQUIRE_CRED( status: { 458752 0 "No credentials were supplied, or the credentials were unavailable or inaccessible" "" [ ] } output_cred_handle: ) 222s [2024/03/20 04:41:05]: Total sent bytes: 1200 222s [2024/03/20 04:41:05]: Total received bytes: 1756 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: Connection matched service HTTP 222s [CID 11][2024/03/20 04:41:05]: gp_rpc_execute: executing 1 (GSSX_INDICATE_MECHS) for service "HTTP", euid: 33,socket: (null) 222s GSSX_ARG_INDICATE_MECHS( call_ctx: { "" [ ] } ) 222s 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: [ ] ) 222s [2024/03/20 04:41:05]: Total sent bytes: 6572 222s [2024/03/20 04:41:05]: Total received bytes: 1760 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [2024/03/20 04:41:05]: Total received bytes: 2836 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: Connection matched service HTTP 222s [CID 11][2024/03/20 04:41:05]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 222s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .......y........... ] 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 ) 222s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .......y........... ] 0 } ) 222s [2024/03/20 04:41:05]: Total sent bytes: 7616 222s [2024/03/20 04:41:05]: Total received bytes: 4684 222s [2024/03/20 04:41:05]: Idle for: 0 seconds 222s [CID 11][2024/03/20 04:41:05]: Connection matched service HTTP 222s [CID 11][2024/03/20 04:41:05]: gp_rpc_execute: executing 9 (GSSX_ACCEPT_SEC_CONTEXT) for service "HTTP", euid: 33,socket: (null) 222s GSSX_ARG_ACCEPT_SEC_CONTEXT( call_ctx: { "" [ ] } context_handle: cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .......y........... ] 0 } input_token: [ ........H.......... ] input_cb: ret_deleg_cred: 1 ) 222s 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-arm64-gssproxy-20240320-043724-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" 86698 438 0 1 } output_token: [ .......H........... ] delegated_cred_handle: ) 222s [2024/03/20 04:41:05]: Total sent bytes: 9908 222s [2024/03/20 04:41:06]: Client [2024/03/20 04:41:06]: (/usr/sbin/apache2) [2024/03/20 04:41:06]: connected (fd = 12)[2024/03/20 04:41:06]: (pid = 2529) (uid = 0) (gid = 0)[2024/03/20 04:41:06]: 222s [2024/03/20 04:41:06]: Total received bytes: 4688 222s [2024/03/20 04:41:06]: Idle for: 1 seconds 222s [2024/03/20 04:41:06]: Total received bytes: 4740 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [CID 12][2024/03/20 04:41:06]: No matching service found 222s [2024/03/20 04:41:06]: Client [2024/03/20 04:41:06]: (/usr/sbin/apache2) [2024/03/20 04:41:06]: connected (fd = 12)[2024/03/20 04:41:06]: (pid = 2529) (uid = 0) (gid = 0)[2024/03/20 04:41:06]: 222s [2024/03/20 04:41:06]: Total received bytes: 4744 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [2024/03/20 04:41:06]: Total received bytes: 4796 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [CID 12][2024/03/20 04:41:06]: No matching service found 222s [2024/03/20 04:41:06]: Client [2024/03/20 04:41:06]: (/usr/sbin/apache2) [2024/03/20 04:41:06]: connected (fd = 12)[2024/03/20 04:41:06]: (pid = 2529) (uid = 0) (gid = 0)[2024/03/20 04:41:06]: 222s [2024/03/20 04:41:06]: Total received bytes: 4800 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [CID 12][2024/03/20 04:41:06]: No matching service found 222s [2024/03/20 04:41:06]: Total received bytes: 4852 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [2024/03/20 04:41:06]: Client [2024/03/20 04:41:06]: (/usr/sbin/apache2) [2024/03/20 04:41:06]: connected (fd = 12)[2024/03/20 04:41:06]: (pid = 2529) (uid = 0) (gid = 0)[2024/03/20 04:41:06]: 222s [2024/03/20 04:41:06]: Total received bytes: 4856 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [2024/03/20 04:41:06]: Total received bytes: 4908 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [CID 12][2024/03/20 04:41:06]: No matching service found 222s [2024/03/20 04:41:06]: Client [2024/03/20 04:41:06]: (/usr/sbin/apache2) [2024/03/20 04:41:06]: connected (fd = 12)[2024/03/20 04:41:06]: (pid = 2529) (uid = 0) (gid = 0)[2024/03/20 04:41:06]: 222s [2024/03/20 04:41:06]: Total received bytes: 4912 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [2024/03/20 04:41:06]: Total received bytes: 4964 222s [2024/03/20 04:41:06]: Idle for: 0 seconds 222s [CID 12][2024/03/20 04:41:06]: No matching service found 222s ----- BEGIN FILE /tmp/out-gssproxy-out ----- 222s ==> /var/log/apache2/error.log <== 222s [Wed Mar 20 04:40:52.142572 2024] [mpm_event:notice] [pid 1798:tid 267775789424672] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 222s [Wed Mar 20 04:40:52.142682 2024] [core:notice] [pid 1798:tid 267775789424672] AH00094: Command line: '/usr/sbin/apache2' 222s [Wed Mar 20 04:41:02.149937 2024] [mpm_event:notice] [pid 1798:tid 267775789424672] AH00492: caught SIGWINCH, shutting down gracefully 222s [Wed Mar 20 04:41:05.490871 2024] [mpm_event:notice] [pid 2458:tid 261009133535264] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 222s [Wed Mar 20 04:41:05.490980 2024] [core:notice] [pid 2458:tid 261009133535264] AH00094: Command line: '/usr/sbin/apache2' 222s [Wed Mar 20 04:41:06.193514 2024] [mpm_event:notice] [pid 2458:tid 261009133535264] AH00492: caught SIGWINCH, shutting down gracefully 222s 222s ==> /var/log/apache2/access.log <== 222s 127.0.0.1 - root@GSSPROXY.EXAMPLE.ORG [20/Mar/2024:04:41:05 +0000] "GET /gssproxy-autopkgtest/foo HTTP/1.1" 200 491 "-" "curl/8.5.0" 222s + echo ----- BEGIN FILE /tmp/out-gssproxy-out ----- 222s + cat /tmp/out-gssproxy-out 222s + tail -v /var/log/apache2/error.log /var/log/apache2/access.log 222s + mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 222s + mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 222s + mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 222s + 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 222s rm: cannot remove '/var/lib/krb5kdc/gssproxy-stash': No such file or directory 222s + test -d /etc/systemd/system/apache2.service.d 222s + rmdir /etc/systemd/system/apache2.service.d 222s + rmdir /var/www/html/gssproxy-autopkgtest 222s /tmp/autopkgtest.Bm9ajX/wrapper.sh: Killing leaked background processes: 2367 222s PID TTY STAT TIME COMMAND 222s 2367 ? S 0:00 tail --pid=2335 -F /var/log/syslog 223s autopkgtest [04:41:07]: test gssproxy-apache: -----------------------] 223s gssproxy-apache PASS 223s autopkgtest [04:41:07]: test gssproxy-apache: - - - - - - - - - - results - - - - - - - - - - 224s autopkgtest [04:41:08]: @@@@@@@@@@@@@@@@@@@@ summary 224s gssproxy-apache PASS 228s Creating nova instance adt-noble-arm64-gssproxy-20240320-043724-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240319.img (UUID 821fd622-e452-4ae8-b5c8-959e73b1612a)...