0s autopkgtest [07:49:18]: starting date and time: 2024-03-25 07:49:18+0000 0s autopkgtest [07:49:18]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [07:49:18]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.bbajepe0/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@bos01-arm64-12.secgroup --name adt-noble-arm64-gssproxy-20240325-074917-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://us.ports.ubuntu.com/ubuntu-ports/ 122s autopkgtest [07:51:20]: testbed dpkg architecture: arm64 123s autopkgtest [07:51:21]: testbed apt version: 2.7.12 123s autopkgtest [07:51:21]: @@@@@@@@@@@@@@@@@@@@ test bed setup 124s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 124s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [496 kB] 125s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.8 kB] 125s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 125s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3987 kB] 125s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [708 kB] 125s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 125s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.7 kB] 125s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 125s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4365 kB] 125s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 125s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [71.0 kB] 125s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 130s Fetched 9853 kB in 3s (3571 kB/s) 131s Reading package lists... 134s Reading package lists... 135s Building dependency tree... 135s Reading state information... 136s Calculating upgrade... 137s The following packages will be REMOVED: 137s libssl3 137s The following NEW packages will be installed: 137s libssl3t64 137s The following packages have been kept back: 137s curl 137s The following packages will be upgraded: 137s openssl 137s 1 upgraded, 1 newly installed, 1 to remove and 1 not upgraded. 137s Need to get 2777 kB of archives. 137s After this operation, 139 kB of additional disk space will be used. 137s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu2 [985 kB] 137s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu2 [1793 kB] 138s Fetched 2777 kB in 1s (3985 kB/s) 138s (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 ... 75911 files and directories currently installed.) 138s Preparing to unpack .../openssl_3.0.13-0ubuntu2_arm64.deb ... 138s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 139s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 139s wget depends on libssl3 (>= 3.0.0). 139s u-boot-tools depends on libssl3 (>= 3.0.0). 139s tnftp depends on libssl3 (>= 3.0.0). 139s tcpdump depends on libssl3 (>= 3.0.0). 139s systemd-resolved depends on libssl3 (>= 3.0.0). 139s systemd depends on libssl3 (>= 3.0.0). 139s sudo depends on libssl3 (>= 3.0.0). 139s sbsigntool depends on libssl3 (>= 3.0.0). 139s rsync depends on libssl3 (>= 3.0.0). 139s python3-cryptography depends on libssl3 (>= 3.0.0). 139s openssh-server depends on libssl3 (>= 3.0.10). 139s openssh-client depends on libssl3 (>= 3.0.10). 139s mtd-utils depends on libssl3 (>= 3.0.0). 139s mokutil depends on libssl3 (>= 3.0.0). 139s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 139s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 139s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 139s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 139s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 139s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 139s libpython3.11-minimal:arm64 depends on libssl3 (>= 3.0.0). 139s libnvme1 depends on libssl3 (>= 3.0.0). 139s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 139s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 139s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 139s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 139s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 139s kmod depends on libssl3 (>= 3.0.0). 139s dhcpcd-base depends on libssl3 (>= 3.0.0). 139s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 139s 139s (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 ... 75911 files and directories currently installed.) 139s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 139s Selecting previously unselected package libssl3t64:arm64. 139s (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 ... 75900 files and directories currently installed.) 139s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_arm64.deb ... 139s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu2) ... 139s Setting up libssl3t64:arm64 (3.0.13-0ubuntu2) ... 139s Setting up openssl (3.0.13-0ubuntu2) ... 139s Processing triggers for man-db (2.12.0-3) ... 140s Processing triggers for libc-bin (2.39-0ubuntu6) ... 140s Reading package lists... 141s Building dependency tree... 141s Reading state information... 142s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 143s sh: Attempting to set up Debian/Ubuntu apt sources automatically 143s sh: Distribution appears to be Ubuntu 144s Reading package lists... 145s Building dependency tree... 145s Reading state information... 146s eatmydata is already the newest version (131-1). 146s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 147s dbus is already the newest version (1.14.10-4ubuntu1). 147s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 147s Reading package lists... 148s Building dependency tree... 148s Reading state information... 149s rng-tools-debian is already the newest version (2.4). 149s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 149s Reading package lists... 149s Building dependency tree... 149s Reading state information... 150s The following packages will be REMOVED: 150s cloud-init* python3-configobj* python3-debconf* 151s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 151s After this operation, 3256 kB disk space will be freed. 151s (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 ... 75913 files and directories currently installed.) 151s Removing cloud-init (24.1.2-0ubuntu1) ... 152s Removing python3-configobj (5.0.8-3) ... 152s Removing python3-debconf (1.5.86) ... 152s Processing triggers for man-db (2.12.0-3) ... 153s (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 ... 75524 files and directories currently installed.) 153s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 154s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 154s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 154s invoke-rc.d: policy-rc.d denied execution of try-restart. 154s Reading package lists... 155s Building dependency tree... 155s 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 161s Reading package lists... 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 163s Calculating upgrade... 164s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 164s Reading package lists... 164s Building dependency tree... 164s Reading state information... 165s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 166s autopkgtest [07:52:04]: rebooting testbed after setup commands that affected boot 333s autopkgtest [07:54:51]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 335s autopkgtest [07:54:53]: @@@@@@@@@@@@@@@@@@@@ apt-source gssproxy 337s Get:1 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (dsc) [1699 B] 337s Get:2 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (tar) [190 kB] 337s Get:3 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (diff) [6864 B] 338s gpgv: Signature made Fri Jan 12 10:48:47 2024 UTC 338s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 338s gpgv: issuer "simon@josefsson.org" 338s gpgv: Can't check signature: No public key 338s dpkg-source: warning: cannot verify inline signature for ./gssproxy_0.9.2-2.dsc: no acceptable signature found 338s autopkgtest [07:54:56]: testing package gssproxy version 0.9.2-2 338s autopkgtest [07:54:56]: build not needed 339s autopkgtest [07:54:57]: test gssproxy-apache: preparing testbed 341s Reading package lists... 341s Building dependency tree... 341s Reading state information... 342s Starting pkgProblemResolver with broken count: 0 342s Starting 2 pkgProblemResolver with broken count: 0 342s Done 343s The following additional packages will be installed: 343s apache2 apache2-bin apache2-data apache2-utils gssproxy krb5-admin-server 343s krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi libapr1 343s libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libbasicobjects0 343s libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 libkadm5clnt-mit12 343s libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 libref-array1 343s libverto-libevent1 libverto1 343s Suggested packages: 343s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 343s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 343s Recommended packages: 343s ssl-cert 343s The following NEW packages will be installed: 343s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep gssproxy 343s krb5-admin-server krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi 343s libapr1 libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap 343s libbasicobjects0 libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 343s libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 343s libref-array1 libverto-libevent1 libverto1 343s 0 upgraded, 28 newly installed, 0 to remove and 0 not upgraded. 343s Need to get 3059 kB/3059 kB of archives. 343s After this operation, 18.5 MB of additional disk space will be used. 343s Get:1 /tmp/autopkgtest.KbyZLc/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [752 B] 343s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libapr1 arm64 1.7.2-3 [103 kB] 344s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1 arm64 1.6.3-1ubuntu1 [91.7 kB] 344s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1-dbd-sqlite3 arm64 1.6.3-1ubuntu1 [11.0 kB] 344s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libaprutil1-ldap arm64 1.6.3-1ubuntu1 [8972 B] 344s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 liblua5.4-0 arm64 5.4.6-3 [158 kB] 344s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-bin arm64 2.4.58-1ubuntu2 [1316 kB] 344s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-data all 2.4.58-1ubuntu2 [163 kB] 344s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 apache2-utils arm64 2.4.58-1ubuntu2 [94.9 kB] 344s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 apache2 arm64 2.4.58-1ubuntu2 [90.2 kB] 344s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 krb5-config all 2.7 [22.0 kB] 344s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 libgssrpc4 arm64 1.20.1-5build1 [57.4 kB] 344s Get:13 http://ftpmaster.internal/ubuntu noble/main arm64 libkadm5clnt-mit12 arm64 1.20.1-5build1 [39.9 kB] 344s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 libkdb5-10 arm64 1.20.1-5build1 [39.8 kB] 344s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 libkadm5srv-mit12 arm64 1.20.1-5build1 [53.2 kB] 344s Get:16 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-user arm64 1.20.1-5build1 [108 kB] 344s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 libevent-2.1-7 arm64 2.1.12-stable-9 [138 kB] 344s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libverto1 arm64 0.3.1-1ubuntu5 [10.2 kB] 344s Get:19 http://ftpmaster.internal/ubuntu noble/main arm64 libverto-libevent1 arm64 0.3.1-1ubuntu5 [5848 B] 344s Get:20 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-kdc arm64 1.20.1-5build1 [180 kB] 344s Get:21 http://ftpmaster.internal/ubuntu noble/universe arm64 krb5-admin-server arm64 1.20.1-5build1 [94.6 kB] 344s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 libbasicobjects0 arm64 0.6.2-2 [5586 B] 344s Get:23 http://ftpmaster.internal/ubuntu noble/main arm64 libcollection4 arm64 0.6.2-2 [23.0 kB] 344s Get:24 http://ftpmaster.internal/ubuntu noble/main arm64 libpath-utils1 arm64 0.6.2-2 [8722 B] 344s Get:25 http://ftpmaster.internal/ubuntu noble/main arm64 libref-array1 arm64 0.6.2-2 [7042 B] 344s Get:26 http://ftpmaster.internal/ubuntu noble/main arm64 libini-config5 arm64 0.6.2-2 [43.7 kB] 344s Get:27 http://ftpmaster.internal/ubuntu noble/universe arm64 gssproxy arm64 0.9.2-2 [124 kB] 344s Get:28 http://ftpmaster.internal/ubuntu noble/universe arm64 libapache2-mod-auth-gssapi arm64 1.6.4-3 [60.8 kB] 345s Preconfiguring packages ... 345s Fetched 3059 kB in 1s (3270 kB/s) 345s Selecting previously unselected package libapr1:arm64. 346s (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 ... 75469 files and directories currently installed.) 346s Preparing to unpack .../00-libapr1_1.7.2-3_arm64.deb ... 346s Unpacking libapr1:arm64 (1.7.2-3) ... 346s Selecting previously unselected package libaprutil1:arm64. 346s Preparing to unpack .../01-libaprutil1_1.6.3-1ubuntu1_arm64.deb ... 346s Unpacking libaprutil1:arm64 (1.6.3-1ubuntu1) ... 346s Selecting previously unselected package libaprutil1-dbd-sqlite3:arm64. 346s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-1ubuntu1_arm64.deb ... 346s Unpacking libaprutil1-dbd-sqlite3:arm64 (1.6.3-1ubuntu1) ... 346s Selecting previously unselected package libaprutil1-ldap:arm64. 346s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-1ubuntu1_arm64.deb ... 346s Unpacking libaprutil1-ldap:arm64 (1.6.3-1ubuntu1) ... 346s Selecting previously unselected package liblua5.4-0:arm64. 346s Preparing to unpack .../04-liblua5.4-0_5.4.6-3_arm64.deb ... 346s Unpacking liblua5.4-0:arm64 (5.4.6-3) ... 346s Selecting previously unselected package apache2-bin. 346s Preparing to unpack .../05-apache2-bin_2.4.58-1ubuntu2_arm64.deb ... 346s Unpacking apache2-bin (2.4.58-1ubuntu2) ... 346s Selecting previously unselected package apache2-data. 346s Preparing to unpack .../06-apache2-data_2.4.58-1ubuntu2_all.deb ... 346s Unpacking apache2-data (2.4.58-1ubuntu2) ... 346s Selecting previously unselected package apache2-utils. 346s Preparing to unpack .../07-apache2-utils_2.4.58-1ubuntu2_arm64.deb ... 346s Unpacking apache2-utils (2.4.58-1ubuntu2) ... 346s Selecting previously unselected package apache2. 346s Preparing to unpack .../08-apache2_2.4.58-1ubuntu2_arm64.deb ... 346s Unpacking apache2 (2.4.58-1ubuntu2) ... 346s Selecting previously unselected package krb5-config. 346s Preparing to unpack .../09-krb5-config_2.7_all.deb ... 346s Unpacking krb5-config (2.7) ... 346s Selecting previously unselected package libgssrpc4:arm64. 346s Preparing to unpack .../10-libgssrpc4_1.20.1-5build1_arm64.deb ... 346s Unpacking libgssrpc4:arm64 (1.20.1-5build1) ... 346s Selecting previously unselected package libkadm5clnt-mit12:arm64. 346s Preparing to unpack .../11-libkadm5clnt-mit12_1.20.1-5build1_arm64.deb ... 346s Unpacking libkadm5clnt-mit12:arm64 (1.20.1-5build1) ... 346s Selecting previously unselected package libkdb5-10:arm64. 346s Preparing to unpack .../12-libkdb5-10_1.20.1-5build1_arm64.deb ... 346s Unpacking libkdb5-10:arm64 (1.20.1-5build1) ... 346s Selecting previously unselected package libkadm5srv-mit12:arm64. 346s Preparing to unpack .../13-libkadm5srv-mit12_1.20.1-5build1_arm64.deb ... 346s Unpacking libkadm5srv-mit12:arm64 (1.20.1-5build1) ... 346s Selecting previously unselected package krb5-user. 346s Preparing to unpack .../14-krb5-user_1.20.1-5build1_arm64.deb ... 346s Unpacking krb5-user (1.20.1-5build1) ... 347s Selecting previously unselected package libevent-2.1-7:arm64. 347s Preparing to unpack .../15-libevent-2.1-7_2.1.12-stable-9_arm64.deb ... 347s Unpacking libevent-2.1-7:arm64 (2.1.12-stable-9) ... 347s Selecting previously unselected package libverto1:arm64. 347s Preparing to unpack .../16-libverto1_0.3.1-1ubuntu5_arm64.deb ... 347s Unpacking libverto1:arm64 (0.3.1-1ubuntu5) ... 347s Selecting previously unselected package libverto-libevent1:arm64. 347s Preparing to unpack .../17-libverto-libevent1_0.3.1-1ubuntu5_arm64.deb ... 347s Unpacking libverto-libevent1:arm64 (0.3.1-1ubuntu5) ... 347s Selecting previously unselected package krb5-kdc. 347s Preparing to unpack .../18-krb5-kdc_1.20.1-5build1_arm64.deb ... 347s Unpacking krb5-kdc (1.20.1-5build1) ... 347s Selecting previously unselected package krb5-admin-server. 347s Preparing to unpack .../19-krb5-admin-server_1.20.1-5build1_arm64.deb ... 347s Unpacking krb5-admin-server (1.20.1-5build1) ... 347s Selecting previously unselected package libbasicobjects0:arm64. 347s Preparing to unpack .../20-libbasicobjects0_0.6.2-2_arm64.deb ... 347s Unpacking libbasicobjects0:arm64 (0.6.2-2) ... 347s Selecting previously unselected package libcollection4:arm64. 347s Preparing to unpack .../21-libcollection4_0.6.2-2_arm64.deb ... 347s Unpacking libcollection4:arm64 (0.6.2-2) ... 347s Selecting previously unselected package libpath-utils1:arm64. 347s Preparing to unpack .../22-libpath-utils1_0.6.2-2_arm64.deb ... 347s Unpacking libpath-utils1:arm64 (0.6.2-2) ... 347s Selecting previously unselected package libref-array1:arm64. 347s Preparing to unpack .../23-libref-array1_0.6.2-2_arm64.deb ... 347s Unpacking libref-array1:arm64 (0.6.2-2) ... 347s Selecting previously unselected package libini-config5:arm64. 347s Preparing to unpack .../24-libini-config5_0.6.2-2_arm64.deb ... 347s Unpacking libini-config5:arm64 (0.6.2-2) ... 347s Selecting previously unselected package gssproxy. 347s Preparing to unpack .../25-gssproxy_0.9.2-2_arm64.deb ... 347s Unpacking gssproxy (0.9.2-2) ... 347s Selecting previously unselected package libapache2-mod-auth-gssapi. 347s Preparing to unpack .../26-libapache2-mod-auth-gssapi_1.6.4-3_arm64.deb ... 347s Unpacking libapache2-mod-auth-gssapi (1.6.4-3) ... 347s Selecting previously unselected package autopkgtest-satdep. 347s Preparing to unpack .../27-1-autopkgtest-satdep.deb ... 347s Unpacking autopkgtest-satdep (0) ... 347s Setting up libpath-utils1:arm64 (0.6.2-2) ... 347s Setting up libbasicobjects0:arm64 (0.6.2-2) ... 347s Setting up libapr1:arm64 (1.7.2-3) ... 347s Setting up libgssrpc4:arm64 (1.20.1-5build1) ... 347s Setting up krb5-config (2.7) ... 348s Setting up libevent-2.1-7:arm64 (2.1.12-stable-9) ... 348s Setting up libcollection4:arm64 (0.6.2-2) ... 348s Setting up liblua5.4-0:arm64 (5.4.6-3) ... 348s Setting up libref-array1:arm64 (0.6.2-2) ... 348s Setting up apache2-data (2.4.58-1ubuntu2) ... 348s Setting up libkadm5clnt-mit12:arm64 (1.20.1-5build1) ... 348s Setting up libaprutil1:arm64 (1.6.3-1ubuntu1) ... 348s Setting up libkdb5-10:arm64 (1.20.1-5build1) ... 348s Setting up libini-config5:arm64 (0.6.2-2) ... 348s Setting up libaprutil1-ldap:arm64 (1.6.3-1ubuntu1) ... 348s Setting up libaprutil1-dbd-sqlite3:arm64 (1.6.3-1ubuntu1) ... 348s Setting up libkadm5srv-mit12:arm64 (1.20.1-5build1) ... 348s Setting up apache2-utils (2.4.58-1ubuntu2) ... 348s Setting up apache2-bin (2.4.58-1ubuntu2) ... 348s Setting up krb5-user (1.20.1-5build1) ... 348s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 348s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 348s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 348s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 348s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 348s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 348s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 348s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 348s Setting up libapache2-mod-auth-gssapi (1.6.4-3) ... 348s Package apache2 is not configured yet. Will defer actions by package libapache2-mod-auth-gssapi. 348s Setting up apache2 (2.4.58-1ubuntu2) ... 348s Enabling module mpm_event. 348s Enabling module authz_core. 348s Enabling module authz_host. 348s Enabling module authn_core. 348s Enabling module auth_basic. 348s Enabling module access_compat. 348s Enabling module authn_file. 348s Enabling module authz_user. 348s Enabling module alias. 348s Enabling module dir. 348s Enabling module autoindex. 349s Enabling module env. 349s Enabling module mime. 349s Enabling module negotiation. 349s Enabling module setenvif. 349s Enabling module filter. 349s Enabling module deflate. 349s Enabling module status. 349s Enabling module reqtimeout. 349s Enabling conf charset. 349s Enabling conf localized-error-pages. 349s Enabling conf other-vhosts-access-log. 349s Enabling conf security. 349s Enabling conf serve-cgi-bin. 349s Enabling site 000-default. 350s info: Executing deferred 'a2enmod auth_gssapi' for package libapache2-mod-auth-gssapi 350s Enabling module auth_gssapi. 351s Created symlink /etc/systemd/system/multi-user.target.wants/apache2.service → /usr/lib/systemd/system/apache2.service. 352s Created symlink /etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service → /usr/lib/systemd/system/apache-htcacheclean.service. 356s Setting up libverto-libevent1:arm64 (0.3.1-1ubuntu5) ... 356s Setting up libverto1:arm64 (0.3.1-1ubuntu5) ... 356s Setting up gssproxy (0.9.2-2) ... 356s Created symlink /etc/systemd/user/sockets.target.wants/gssuserproxy.socket → /usr/lib/systemd/user/gssuserproxy.socket. 356s Created symlink /etc/systemd/system/multi-user.target.wants/gssproxy.service → /usr/lib/systemd/system/gssproxy.service. 356s Setting up krb5-kdc (1.20.1-5build1) ... 357s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service. 358s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 358s Setting up krb5-admin-server (1.20.1-5build1) ... 359s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service. 360s Setting up autopkgtest-satdep (0) ... 360s Processing triggers for libc-bin (2.39-0ubuntu6) ... 360s Processing triggers for ufw (0.36.2-5) ... 360s Processing triggers for man-db (2.12.0-3) ... 367s (Reading database ... 76377 files and directories currently installed.) 367s Removing autopkgtest-satdep (0) ... 367s autopkgtest [07:55:25]: test gssproxy-apache: [----------------------- 367s /usr/bin/ss 367s /usr/bin/id 367s /usr/bin/hostname 367s /usr/bin/dpkg 367s + command -v ss 367s + command -v id 367s + command -v hostname 367s + command -v dpkg 367s + dpkg -V krb5-kdc 367s + dpkg -V krb5-admin-server 367s + dpkg -V apache2 368s + dpkg -V libapache2-mod-auth-gssapi 368s + dpkg -V gssproxy 368s + dpkg -V curl 368s + service apache2 stop 369s + service krb5-kdc stop 370s /usr/bin/systemctl 370s + command -v systemctl 370s + service gssproxy stop 371s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin 371s + trap 371s set +e; 371s kdestroy 371s service apache2 stop 371s service krb5-kdc stop 371s for f in /tmp/out-*; 371s do echo ----- BEGIN FILE $f ----- 371s cat $f 371s done 371s tail -v /var/log/apache2/error.log /var/log/apache2/access.log 371s mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 371s mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 371s mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 371s 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 371s test -d /etc/systemd/system/apache2.service.d && rmdir /etc/systemd/system/apache2.service.d 371s rmdir /var/www/html/gssproxy-autopkgtest 371s 0 INT QUIT ABRT PIPE TERM 371s + tail --pid=2254 -F /var/log/syslog 371s + id -un 371s 2024-03-25T07:55:18.704728+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo (kadmind)[2117]: krb5-admin-server.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 371s 2024-03-25T07:55:18.714480+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo kadmind[2117]: kadmind: Cannot open DB2 database '/var/lib/krb5kdc/principal': No such file or directory while initializing, aborting 371s 2024-03-25T07:55:18.719071+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Main process exited, code=exited, status=1/FAILURE 371s 2024-03-25T07:55:18.720165+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: krb5-admin-server.service: Failed with result 'exit-code'. 371s 2024-03-25T07:55:27.305697+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 371s 2024-03-25T07:55:27.361779+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 371s 2024-03-25T07:55:27.362382+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 371s 2024-03-25T07:55:29.802371+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopping gssproxy.service - GSSAPI Proxy Daemon... 371s 2024-03-25T07:55:29.807126+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: gssproxy.service: Deactivated successfully. 371s 2024-03-25T07:55:29.807696+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopped gssproxy.service - GSSAPI Proxy Daemon. 371s + my_user=root 371s + hostname -f 371s + my_hostname=adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal 371s + hostname -d 371s + my_domainname=novalocal 371s + test -f /etc/krb5kdc/kdc.conf-gssproxysave 371s + cp /etc/krb5kdc/kdc.conf /etc/krb5kdc/kdc.conf-gssproxysave 371s + cat 371s + test -f /etc/krb5.conf-gssproxysave 371s + cp /etc/krb5.conf /etc/krb5.conf-gssproxysave 371s + cat 371s + kdb5_util -P foo create -s 371s + kadmin.local addprinc -randkey HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal 371s + kadmin.local addprinc -pw bar root 371s Initializing database '/var/lib/krb5kdc/gssproxy-principal' for realm 'GSSPROXY.EXAMPLE.ORG', 371s master key name 'K/M@GSSPROXY.EXAMPLE.ORG' 371s + kadmin.local ktadd -k /etc/gssproxy/httpd.keytab HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal 371s Entry for principal HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 371s Entry for principal HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab. 371s + service krb5-kdc start 373s 2024-03-25T07:55:31.124007+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center... 373s 2024-03-25T07:55:31.157384+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo (krb5kdc)[2304]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS 373s 2024-03-25T07:55:31.174496+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center. 373s + i=0 373s + + grep 0.0.0.0:17643 373s + grep LISTEN 373s ss -na 373s tcp LISTEN 0 5 0.0.0.0:17643 0.0.0.0:* 373s + kdestroy 373s kdestroy: No credentials cache found while destroying cache 373s + echo bar 373s + kinit root 374s Password for root@GSSPROXY.EXAMPLE.ORG: 374s + test -f /etc/gssproxy/gssproxy.conf-gssproxysave 374s + cp /etc/gssproxy/gssproxy.conf /etc/gssproxy/gssproxy.conf-gssproxysave/usr/bin/systemctl 374s /usr/bin/systemctl 374s 2024-03-25T07:55:32.367801+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Reloading requested from client PID 2331 ('systemctl') (unit session-1.scope)... 374s 2024-03-25T07:55:32.368612+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Reloading... 374s 374s + cat 374s + cat 374s + command -v systemctl 374s + cat 374s + KRB5RCACHEDIR=/var/lib/gssproxy/rcache /usr/sbin/gssproxy -i --debug-level=2 374s + command -v systemctl 374s + mkdir -p /etc/systemd/system/apache2.service.d 374s + cat 374s + systemctl daemon-reload 375s 2024-03-25T07:55:33.019383+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Reloading finished in 648 ms. 375s + service apache2 start 376s 2024-03-25T07:55:34.341685+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Starting apache2.service - The Apache HTTP Server... 376s 2024-03-25T07:55:34.390599+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Started apache2.service - The Apache HTTP Server. 376s + mkdir -p /var/www/html/gssproxy-autopkgtest 376s + echo Ossifrage 376s + curl --verbose --negotiate -u : http://adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal/gssproxy-autopkgtest/foo 376s + grep Ossifrage /tmp/out-curl-out 376s Ossifrage 376s + echoPASS: /tmp/autopkgtest.KbyZLc/build.TQ5/src/debian/tests/gssproxy-apache 376s PASS: /tmp/autopkgtest.KbyZLc/build.TQ5/src/debian/tests/gssproxy-apache 376s + exit 0 376s + set +e 376s + kdestroy 376s + service apache2 stop 378s 2024-03-25T07:55:35.705440+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server... 378s + service krb5-kdc stop 378s 2024-03-25T07:55:35.764304+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully. 378s 2024-03-25T07:55:35.764914+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server. 379s 2024-03-25T07:55:37.003203+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopping krb5-kdc.service - Kerberos 5 Key Distribution Center... 379s 2024-03-25T07:55:37.007344+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: krb5-kdc.service: Deactivated successfully. 379s 2024-03-25T07:55:37.007475+00:00 adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo systemd[1]: Stopped krb5-kdc.service - Kerberos 5 Key Distribution Center. 379s + echo ----- BEGIN----- BEGIN FILE /tmp/out-curl-err ----- 379s FILE /tmp/out-curl-err ----- 379s + cat /tmp/out-curl-err 379s * 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' 379s % Total % Received % Xferd Average Speed Time Time Time Current 379s Dload Upload Total Spent Left Speed 379s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0* Host adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal:80 was resolved. 379s * IPv6: (none) 379s * IPv4: 127.0.1.1 379s * Trying 127.0.1.1:80... 379s * Connected to adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal (127.0.1.1) port 80 379s * Server auth using Negotiate with user '' 379s > GET /gssproxy-autopkgtest/foo HTTP/1.1 379s > Host: adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal 379s > Authorization: Negotiate YIIDTgYGKwYBBQUCoIIDQjCCAz6gDTALBgkqhkiG9xIBAgKiggMrBIIDJ2CCAyMGCSqGSIb3EgECAgEAboIDEjCCAw6gAwIBBaEDAgEOogcDBQAgAAAAo4ICHmGCAhowggIWoAMCAQWhFhsUR1NTUFJPWFkuRVhBTVBMRS5PUkeiXDBaoAMCAQOhUzBRGwRIVFRQG0lhZHQtbm9ibGUtYXJtNjQtZ3NzcHJveHktMjAyNDAzMjUtMDc0OTE3LWp1anUtN2YyMjc1LXByb2QtcHJvcG8ubm92YWxvY2Fso4IBlzCCAZOgAwIBEqEDAgECooIBhQSCAYGE+d4/BsN79k++dC/IGDlkB8L+hU3gQ9+Ql1gLbpyRF7buhOsN4JvA7nVESuhJUP3tS8Q+FLx1F1ofD3Mi+DroN53gOhltXddSSxCjW1hT1VDTUkNIQy49ftbs7vix/U6G1rDhaQ15UN9Oaovsb2ST+yjTAevUPLGP9RQ1cRcG3lTDArwytg+0EspOsPzJr+9UMGQNlo/qo3pVKW5i1L5TXwL0MY5ci7u2lcbynIKMFpB0cLZCOTL+V94ID7yvLFh8kUsWC2LGNKyAL5H20ZIS+qUQmppmcEpz5azPsrv3FIfXsOqGcWCie7Z0E7pzyU7Lq2BDeGLyzgvupe8IFxOIL5JIYmolORdpSMTX/nff93fM/en9eOBQxyrKxdefjuO6VOpDQ1nXmRhRU8pjl+i+Hn16C5C2q/01OsE//BMN5TqAGSGO+53WYvFl5AGS3pYo0fjusHjxPGmKuGAAz6jXZ2HDpgU0ka/b8MRXEpAUQj1IkWusbtgJKthqoq/tTFtppIHWMIHToAMCARKigcsEgcjBr6TvnyRGfJrPsOCA4asQa5jGelM5eOAsB+pP2dXbOg8uS4MOV+cdmxGC2uWVVCaMGOR5L+bQ+/K2rInk2xAvr2YN+baG01+gndFXHNPcNwYMdK3MGYba4g3sQtNp5lgO+AgG3dlHrySGS/zb4fsaJRZLI8PBchuqVnUpTg0T2atRy1l8h/Cq0pXCJFasbUlGJ9z6b/EFAAFncHmbOotYic55rwyX6KYJAzTcrSzlhcUSm77aUiK1ahkrpiTagCP3kIZEDSzLCw== 379s > User-Agent: curl/8.5.0 379s > Accept: */* 379s > 379s < HTTP/1.1 200 OK 379s < Date: Mon, 25 Mar 2024 07:55:34 GMT 379s < Server: Apache/2.4.58 (Ubuntu) 379s < WWW-Authenticate: Negotiate oYG3MIG0oAMKAQChCwYJKoZIhvcSAQICooGfBIGcYIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvN10f+IuYWK2HbMbE1hQI8gElS5qrVhuQruHJS/astr+SdTs6TaL746Mk87TQ5NNP/q2xHx/22PqpfV1yO0MC68VXWjvd0Xu0ywUNLuwD9cjOT+etO0WLRaXC6iTXcj2Y/F25ktM1mjgBgXAD09xe 379s < Last-Modified: Mon, 25 Mar 2024 07:55:34 GMT 379s < ETag: W/"a-614777f94fbfb" 379s < Accept-Ranges: bytes 379s < Content-Length: 10 379s < 379s { [10 bytes data] 379s 100 10 100 10 0 0 420 0 --:--:-- --:--:-- --:--:-- 434 379s * Connection #0 to host adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal left intact 379s ----- BEGIN FILE /tmp/out-curl-out ----- 379s Ossifrage 379s ----- BEGIN FILE /tmp/out-gssproxy-err ----- 379s + echo ----- BEGIN FILE /tmp/out-curl-out ----- 379s + cat /tmp/out-curl-out 379s + echo ----- BEGIN FILE /tmp/out-gssproxy-err ----- 379s + cat /tmp/out-gssproxy-err 379s [2024/03/25 07:55:32]: Debug Level changed to 2 379s [2024/03/25 07:55:32]: Debug Level changed to 2 379s [2024/03/25 07:55:32]: Keytab /etc/krb5.keytab has no content (-1765328203) 379s [2024/03/25 07:55:32]: Service: nfs-server, Enckey: [ephemeral], Enctype: 18 379s [2024/03/25 07:55:32]: Service: HTTP, Keytab: /etc/gssproxy/httpd.keytab, Enctype: 18 379s [2024/03/25 07:55:32]: Kernel doesn't support GSS-Proxy (can't open /proc/net/rpc/use-gss-proxy: 2 (No such file or directory)) 379s [2024/03/25 07:55:32]: Initialization complete. 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [2024/03/25 07:55:34]: Total received bytes: 4 379s [2024/03/25 07:55:34]: Idle for: 1711353334 seconds 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 56 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [2024/03/25 07:55:34]: Total received bytes: 60 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 112 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 168 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [2024/03/25 07:55:34]: Total received bytes: 172 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 224 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 280 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 336 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 392 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Total received bytes: 448 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2375) (uid = 0) (gid = 0)[2024/03/25 07:55:34]: 379s [2024/03/25 07:55:34]: Total received bytes: 504 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: No matching service found 379s [2024/03/25 07:55:34]: Client [2024/03/25 07:55:34]: (/usr/sbin/apache2) [2024/03/25 07:55:34]: connected (fd = 11)[2024/03/25 07:55:34]: (pid = 2378) (uid = 33) (gid = 33)[2024/03/25 07:55:34]: 379s [CID 11][2024/03/25 07:55:34]: Connection matched service HTTP 379s [CID 11][2024/03/25 07:55:34]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 379s 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 ) 379s [2024/03/25 07:55:34]: Total received bytes: 624 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ ....T...m.a....O... ] 0 } ) 379s [2024/03/25 07:55:34]: Total sent bytes: 1044 379s [2024/03/25 07:55:34]: Total received bytes: 1700 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: Connection matched service HTTP 379s [CID 11][2024/03/25 07:55:34]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 379s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ ....T...m.a....O... ] 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 ) 379s GSSX_RES_ACQUIRE_CRED( status: { 458752 0 "No credentials were supplied, or the credentials were unavailable or inaccessible" "" [ ] } output_cred_handle: ) 379s [2024/03/25 07:55:34]: Total sent bytes: 1200 379s [2024/03/25 07:55:34]: Total received bytes: 1704 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [2024/03/25 07:55:34]: Total received bytes: 1756 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: Connection matched service HTTP 379s [CID 11][2024/03/25 07:55:34]: gp_rpc_execute: executing 1 (GSSX_INDICATE_MECHS) for service "HTTP", euid: 33,socket: (null) 379s GSSX_ARG_INDICATE_MECHS( call_ctx: { "" [ ] } ) 379s 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 } + echo ----- BEGIN FILE /tmp/out-gssproxy-out ----- 379s + cat /tmp/out-gssproxy-out 379s + tail -v /var/log/apache2/error.log /var/log/apache2/access.log 379s + mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf 379s + mv /etc/krb5.conf-gssproxysave /etc/krb5.conf 379s + mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf 379s + 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 379s rm: cannot remove '/var/lib/krb5kdc/gssproxy-stash': No such file or directory 379s + test -d /etc/systemd/system/apache2.service.d 379s + rmdir /etc/systemd/system/apache2.service.d 379s + rmdir /var/www/html/gssproxy-autopkgtest 379s /tmp/autopkgtest.KbyZLc/wrapper.sh: Killing leaked background processes: 2286 379s { 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: [ ] ) 379s [2024/03/25 07:55:34]: Total sent bytes: 6572 379s [2024/03/25 07:55:34]: Total received bytes: 2836 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: Connection matched service HTTP 379s [CID 11][2024/03/25 07:55:34]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null) 379s GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [ ] } input_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ ....T...m.a....O... ] 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 ) 379s GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [ ] } output_cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ ....T...m.a....O... ] 0 } ) 379s [2024/03/25 07:55:34]: Total sent bytes: 7616 379s [2024/03/25 07:55:34]: Total received bytes: 4684 379s [2024/03/25 07:55:34]: Idle for: 0 seconds 379s [CID 11][2024/03/25 07:55:34]: Connection matched service HTTP 379s [CID 11][2024/03/25 07:55:34]: gp_rpc_execute: executing 9 (GSSX_ACCEPT_SEC_CONTEXT) for service "HTTP", euid: 33,socket: (null) 379s GSSX_ARG_ACCEPT_SEC_CONTEXT( call_ctx: { "" [ ] } context_handle: cred_handle: { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ ....T...m.a....O... ] 0 } input_token: [ ........H.......... ] input_cb: ret_deleg_cred: 1 ) 379s 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-20240325-074917-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" 86697 438 0 1 } output_token: [ .......H........... ] delegated_cred_handle: ) 379s [2024/03/25 07:55:34]: Total sent bytes: 9908 379s [2024/03/25 07:55:35]: Client [2024/03/25 07:55:35]: (/usr/sbin/apache2) [2024/03/25 07:55:35]: connected (fd = 12)[2024/03/25 07:55:35]: (pid = 2447) (uid = 0) (gid = 0)[2024/03/25 07:55:35]: 379s [2024/03/25 07:55:35]: Total received bytes: 4688 379s [2024/03/25 07:55:35]: Idle for: 1 seconds 379s [2024/03/25 07:55:35]: Total received bytes: 4740 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [CID 12][2024/03/25 07:55:35]: No matching service found 379s [2024/03/25 07:55:35]: Client [2024/03/25 07:55:35]: (/usr/sbin/apache2) [2024/03/25 07:55:35]: connected (fd = 12)[2024/03/25 07:55:35]: (pid = 2447) (uid = 0) (gid = 0)[2024/03/25 07:55:35]: 379s [2024/03/25 07:55:35]: Total received bytes: 4744 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [2024/03/25 07:55:35]: Total received bytes: 4796 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [CID 12][2024/03/25 07:55:35]: No matching service found 379s [2024/03/25 07:55:35]: Client [2024/03/25 07:55:35]: (/usr/sbin/apache2) [2024/03/25 07:55:35]: connected (fd = 12)[2024/03/25 07:55:35]: (pid = 2447) (uid = 0) (gid = 0)[2024/03/25 07:55:35]: 379s [2024/03/25 07:55:35]: Total received bytes: 4800 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [CID 12][2024/03/25 07:55:35]: No matching service found 379s [2024/03/25 07:55:35]: Total received bytes: 4852 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [2024/03/25 07:55:35]: Client [2024/03/25 07:55:35]: (/usr/sbin/apache2) [2024/03/25 07:55:35]: connected (fd = 12)[2024/03/25 07:55:35]: (pid = 2447) (uid = 0) (gid = 0)[2024/03/25 07:55:35]: 379s [2024/03/25 07:55:35]: Total received bytes: 4856 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [2024/03/25 07:55:35]: Total received bytes: 4908 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [CID 12][2024/03/25 07:55:35]: No matching service found 379s [2024/03/25 07:55:35]: Client [2024/03/25 07:55:35]: (/usr/sbin/apache2) [2024/03/25 07:55:35]: connected (fd = 12)[2024/03/25 07:55:35]: (pid = 2447) (uid = 0) (gid = 0)[2024/03/25 07:55:35]: 379s [2024/03/25 07:55:35]: Total received bytes: 4912 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [2024/03/25 07:55:35]: Total received bytes: 4964 379s [2024/03/25 07:55:35]: Idle for: 0 seconds 379s [CID 12][2024/03/25 07:55:35]: No matching service found 379s ----- BEGIN FILE /tmp/out-gssproxy-out ----- 379s ==> /var/log/apache2/error.log <== 379s [Mon Mar 25 07:55:10.736828 2024] [mpm_event:notice] [pid 1723:tid 276062738669600] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 379s [Mon Mar 25 07:55:10.737013 2024] [core:notice] [pid 1723:tid 276062738669600] AH00094: Command line: '/usr/sbin/apache2' 379s [Mon Mar 25 07:55:27.328914 2024] [mpm_event:notice] [pid 1723:tid 276062738669600] AH00492: caught SIGWINCH, shutting down gracefully 379s [Mon Mar 25 07:55:34.398871 2024] [mpm_event:notice] [pid 2376:tid 279696424558624] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations 379s [Mon Mar 25 07:55:34.399034 2024] [core:notice] [pid 2376:tid 279696424558624] AH00094: Command line: '/usr/sbin/apache2' 379s [Mon Mar 25 07:55:35.732443 2024] [mpm_event:notice] [pid 2376:tid 279696424558624] AH00492: caught SIGWINCH, shutting down gracefully 379s 379s ==> /var/log/apache2/access.log <== 379s 127.0.0.1 - root@GSSPROXY.EXAMPLE.ORG [25/Mar/2024:07:55:34 +0000] "GET /gssproxy-autopkgtest/foo HTTP/1.1" 200 491 "-" "curl/8.5.0" 379s PID TTY STAT TIME COMMAND 379s 2286 ? S 0:00 tail --pid=2254 -F /var/log/syslog 379s autopkgtest [07:55:37]: test gssproxy-apache: -----------------------] 379s autopkgtest [07:55:37]: test gssproxy-apache: - - - - - - - - - - results - - - - - - - - - - 379s gssproxy-apache PASS 380s autopkgtest [07:55:38]: @@@@@@@@@@@@@@@@@@@@ summary 380s gssproxy-apache PASS 393s Creating nova instance adt-noble-arm64-gssproxy-20240325-074917-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240324.img (UUID 2df78aec-1bf7-4c70-8bf1-31fba04b7d21)...