0s autopkgtest [17:44:14]: starting date and time: 2024-03-21 17:44:14+0000
  0s autopkgtest [17:44:14]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps
  0s autopkgtest [17:44:14]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gw6wbjpn/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@bos02-s390x-12.secgroup --name adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/
 79s autopkgtest [17:45:33]: testbed dpkg architecture: s390x
 79s autopkgtest [17:45:33]: testbed apt version: 2.7.12
 79s autopkgtest [17:45:33]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 81s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
 81s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB]
 81s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3765 kB]
 82s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB]
 82s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
 82s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [662 kB]
 82s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B]
 82s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B]
 82s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B]
 82s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3983 kB]
 83s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B]
 83s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB]
 83s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B]
 86s Fetched 9141 kB in 4s (2348 kB/s)
 86s Reading package lists...
 89s Reading package lists...
 90s Building dependency tree...
 90s Reading state information...
 90s Calculating upgrade...
 90s The following packages will be upgraded:
 90s   debianutils libselinux1
 90s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 90s Need to get 175 kB of archives.
 90s After this operation, 1024 B of additional disk space will be used.
 90s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB]
 91s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libselinux1 s390x 3.5-2ubuntu1 [84.7 kB]
 91s Fetched 175 kB in 1s (287 kB/s)
 91s (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 ... 52171 files and directories currently installed.)
 91s Preparing to unpack .../debianutils_5.17_s390x.deb ...
 91s Unpacking debianutils (5.17) over (5.16) ...
 91s Setting up debianutils (5.17) ...
 92s (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 ... 52171 files and directories currently installed.)
 92s Preparing to unpack .../libselinux1_3.5-2ubuntu1_s390x.deb ...
 92s Unpacking libselinux1:s390x (3.5-2ubuntu1) over (3.5-2build1) ...
 92s Setting up libselinux1:s390x (3.5-2ubuntu1) ...
 92s Processing triggers for man-db (2.12.0-3) ...
 93s Processing triggers for libc-bin (2.39-0ubuntu2) ...
 93s Reading package lists...
 94s Building dependency tree...
 94s Reading state information...
 94s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 95s Unknown architecture, assuming PC-style ttyS0
 95s sh: Attempting to set up Debian/Ubuntu apt sources automatically
 95s sh: Distribution appears to be Ubuntu
 96s Reading package lists...
 96s Building dependency tree...
 96s Reading state information...
 97s eatmydata is already the newest version (131-1).
 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 97s Reading package lists...
 97s Building dependency tree...
 97s Reading state information...
 97s dbus is already the newest version (1.14.10-4ubuntu1).
 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 97s Reading package lists...
 98s Building dependency tree...
 98s Reading state information...
 98s rng-tools-debian is already the newest version (2.4).
 98s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 98s Reading package lists...
 98s Building dependency tree...
 98s Reading state information...
 98s The following packages will be REMOVED:
 98s   cloud-init* python3-configobj* python3-debconf*
 98s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
 98s After this operation, 3252 kB disk space will be freed.
 99s (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 ... 52171 files and directories currently installed.)
 99s Removing cloud-init (24.1.1-0ubuntu1) ...
 99s Removing python3-configobj (5.0.8-3) ...
 99s Removing python3-debconf (1.5.86) ...
 99s Processing triggers for man-db (2.12.0-3) ...
100s (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 51782 files and directories currently installed.)
100s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ...
100s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
100s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
100s invoke-rc.d: policy-rc.d denied execution of try-restart.
101s Reading package lists...
101s Building dependency tree...
101s Reading state information...
101s linux-generic is already the newest version (6.8.0-11.11+1).
101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
102s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
102s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
102s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
104s Reading package lists...
105s Reading package lists...
105s Building dependency tree...
105s Reading state information...
105s Calculating upgrade...
105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
105s Reading package lists...
105s Building dependency tree...
105s Reading state information...
106s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
106s autopkgtest [17:46:00]: rebooting testbed after setup commands that affected boot
124s autopkgtest [17:46:18]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024
127s autopkgtest [17:46:21]: @@@@@@@@@@@@@@@@@@@@ apt-source gssproxy
129s Get:1 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (dsc) [1699 B]
129s Get:2 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (tar) [190 kB]
129s Get:3 http://ftpmaster.internal/ubuntu noble/universe gssproxy 0.9.2-2 (diff) [6864 B]
129s gpgv: Signature made Fri Jan 12 10:48:47 2024 UTC
129s gpgv:                using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2
129s gpgv:                issuer "simon@josefsson.org"
129s gpgv: Can't check signature: No public key
129s dpkg-source: warning: cannot verify inline signature for ./gssproxy_0.9.2-2.dsc: no acceptable signature found
130s autopkgtest [17:46:24]: testing package gssproxy version 0.9.2-2
130s autopkgtest [17:46:24]: build not needed
130s autopkgtest [17:46:24]: test gssproxy-apache: preparing testbed
134s Reading package lists...
134s Building dependency tree...
134s Reading state information...
134s Starting pkgProblemResolver with broken count: 0
134s Starting 2 pkgProblemResolver with broken count: 0
134s Done
135s The following additional packages will be installed:
135s   apache2 apache2-bin apache2-data apache2-utils gssproxy krb5-admin-server
135s   krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi libapr1
135s   libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libbasicobjects0
135s   libcollection4 libevent-2.1-7 libgssrpc4 libini-config5 libkadm5clnt-mit12
135s   libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1 libref-array1
135s   libverto-libevent1 libverto1
135s Suggested packages:
135s   apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser
135s   krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc
135s Recommended packages:
135s   ssl-cert
135s The following NEW packages will be installed:
135s   apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep gssproxy
135s   krb5-admin-server krb5-config krb5-kdc krb5-user libapache2-mod-auth-gssapi
135s   libapr1 libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap
135s   libbasicobjects0 libcollection4 libevent-2.1-7 libgssrpc4 libini-config5
135s   libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10 liblua5.4-0 libpath-utils1
135s   libref-array1 libverto-libevent1 libverto1
135s 0 upgraded, 28 newly installed, 0 to remove and 0 not upgraded.
135s Need to get 3212 kB/3212 kB of archives.
135s After this operation, 11.4 MB of additional disk space will be used.
135s Get:1 /tmp/autopkgtest.L2zEPf/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [752 B]
135s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libapr1 s390x 1.7.2-3 [105 kB]
135s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1 s390x 1.6.3-1ubuntu1 [91.9 kB]
136s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1-dbd-sqlite3 s390x 1.6.3-1ubuntu1 [11.2 kB]
136s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libaprutil1-ldap s390x 1.6.3-1ubuntu1 [8914 B]
136s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x liblua5.4-0 s390x 5.4.6-3 [179 kB]
136s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x apache2-bin s390x 2.4.58-1ubuntu2 [1412 kB]
137s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x apache2-data all 2.4.58-1ubuntu2 [163 kB]
137s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x apache2-utils s390x 2.4.58-1ubuntu2 [99.6 kB]
137s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x apache2 s390x 2.4.58-1ubuntu2 [90.2 kB]
137s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x krb5-config all 2.7 [22.0 kB]
137s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x libgssrpc4 s390x 1.20.1-5build1 [58.9 kB]
137s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5clnt-mit12 s390x 1.20.1-5build1 [40.5 kB]
137s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x libkdb5-10 s390x 1.20.1-5build1 [41.4 kB]
137s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x libkadm5srv-mit12 s390x 1.20.1-5build1 [55.2 kB]
137s Get:16 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-user s390x 1.20.1-5build1 [110 kB]
137s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x libevent-2.1-7 s390x 2.1.12-stable-9 [144 kB]
137s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x libverto1 s390x 0.3.1-1ubuntu5 [10.6 kB]
137s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x libverto-libevent1 s390x 0.3.1-1ubuntu5 [5810 B]
137s Get:20 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-kdc s390x 1.20.1-5build1 [188 kB]
137s Get:21 http://ftpmaster.internal/ubuntu noble/universe s390x krb5-admin-server s390x 1.20.1-5build1 [95.9 kB]
137s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x libbasicobjects0 s390x 0.6.2-2 [5476 B]
137s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x libcollection4 s390x 0.6.2-2 [23.2 kB]
137s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x libpath-utils1 s390x 0.6.2-2 [8994 B]
137s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x libref-array1 s390x 0.6.2-2 [6880 B]
137s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libini-config5 s390x 0.6.2-2 [45.3 kB]
137s Get:27 http://ftpmaster.internal/ubuntu noble/universe s390x gssproxy s390x 0.9.2-2 [126 kB]
137s Get:28 http://ftpmaster.internal/ubuntu noble/universe s390x libapache2-mod-auth-gssapi s390x 1.6.4-3 [62.5 kB]
138s Preconfiguring packages ...
138s Fetched 3212 kB in 2s (1549 kB/s)
138s Selecting previously unselected package libapr1:s390x.
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 ... 51727 files and directories currently installed.)
138s Preparing to unpack .../00-libapr1_1.7.2-3_s390x.deb ...
138s Unpacking libapr1:s390x (1.7.2-3) ...
138s Selecting previously unselected package libaprutil1:s390x.
138s Preparing to unpack .../01-libaprutil1_1.6.3-1ubuntu1_s390x.deb ...
138s Unpacking libaprutil1:s390x (1.6.3-1ubuntu1) ...
138s Selecting previously unselected package libaprutil1-dbd-sqlite3:s390x.
138s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-1ubuntu1_s390x.deb ...
138s Unpacking libaprutil1-dbd-sqlite3:s390x (1.6.3-1ubuntu1) ...
138s Selecting previously unselected package libaprutil1-ldap:s390x.
138s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-1ubuntu1_s390x.deb ...
138s Unpacking libaprutil1-ldap:s390x (1.6.3-1ubuntu1) ...
138s Selecting previously unselected package liblua5.4-0:s390x.
138s Preparing to unpack .../04-liblua5.4-0_5.4.6-3_s390x.deb ...
138s Unpacking liblua5.4-0:s390x (5.4.6-3) ...
138s Selecting previously unselected package apache2-bin.
138s Preparing to unpack .../05-apache2-bin_2.4.58-1ubuntu2_s390x.deb ...
138s Unpacking apache2-bin (2.4.58-1ubuntu2) ...
139s Selecting previously unselected package apache2-data.
139s Preparing to unpack .../06-apache2-data_2.4.58-1ubuntu2_all.deb ...
139s Unpacking apache2-data (2.4.58-1ubuntu2) ...
139s Selecting previously unselected package apache2-utils.
139s Preparing to unpack .../07-apache2-utils_2.4.58-1ubuntu2_s390x.deb ...
139s Unpacking apache2-utils (2.4.58-1ubuntu2) ...
139s Selecting previously unselected package apache2.
139s Preparing to unpack .../08-apache2_2.4.58-1ubuntu2_s390x.deb ...
139s Unpacking apache2 (2.4.58-1ubuntu2) ...
139s Selecting previously unselected package krb5-config.
139s Preparing to unpack .../09-krb5-config_2.7_all.deb ...
139s Unpacking krb5-config (2.7) ...
139s Selecting previously unselected package libgssrpc4:s390x.
139s Preparing to unpack .../10-libgssrpc4_1.20.1-5build1_s390x.deb ...
139s Unpacking libgssrpc4:s390x (1.20.1-5build1) ...
139s Selecting previously unselected package libkadm5clnt-mit12:s390x.
139s Preparing to unpack .../11-libkadm5clnt-mit12_1.20.1-5build1_s390x.deb ...
139s Unpacking libkadm5clnt-mit12:s390x (1.20.1-5build1) ...
139s Selecting previously unselected package libkdb5-10:s390x.
139s Preparing to unpack .../12-libkdb5-10_1.20.1-5build1_s390x.deb ...
139s Unpacking libkdb5-10:s390x (1.20.1-5build1) ...
139s Selecting previously unselected package libkadm5srv-mit12:s390x.
139s Preparing to unpack .../13-libkadm5srv-mit12_1.20.1-5build1_s390x.deb ...
139s Unpacking libkadm5srv-mit12:s390x (1.20.1-5build1) ...
139s Selecting previously unselected package krb5-user.
139s Preparing to unpack .../14-krb5-user_1.20.1-5build1_s390x.deb ...
139s Unpacking krb5-user (1.20.1-5build1) ...
139s Selecting previously unselected package libevent-2.1-7:s390x.
139s Preparing to unpack .../15-libevent-2.1-7_2.1.12-stable-9_s390x.deb ...
139s Unpacking libevent-2.1-7:s390x (2.1.12-stable-9) ...
139s Selecting previously unselected package libverto1:s390x.
139s Preparing to unpack .../16-libverto1_0.3.1-1ubuntu5_s390x.deb ...
139s Unpacking libverto1:s390x (0.3.1-1ubuntu5) ...
139s Selecting previously unselected package libverto-libevent1:s390x.
139s Preparing to unpack .../17-libverto-libevent1_0.3.1-1ubuntu5_s390x.deb ...
139s Unpacking libverto-libevent1:s390x (0.3.1-1ubuntu5) ...
139s Selecting previously unselected package krb5-kdc.
139s Preparing to unpack .../18-krb5-kdc_1.20.1-5build1_s390x.deb ...
139s Unpacking krb5-kdc (1.20.1-5build1) ...
139s Selecting previously unselected package krb5-admin-server.
139s Preparing to unpack .../19-krb5-admin-server_1.20.1-5build1_s390x.deb ...
139s Unpacking krb5-admin-server (1.20.1-5build1) ...
139s Selecting previously unselected package libbasicobjects0:s390x.
139s Preparing to unpack .../20-libbasicobjects0_0.6.2-2_s390x.deb ...
139s Unpacking libbasicobjects0:s390x (0.6.2-2) ...
139s Selecting previously unselected package libcollection4:s390x.
139s Preparing to unpack .../21-libcollection4_0.6.2-2_s390x.deb ...
139s Unpacking libcollection4:s390x (0.6.2-2) ...
139s Selecting previously unselected package libpath-utils1:s390x.
139s Preparing to unpack .../22-libpath-utils1_0.6.2-2_s390x.deb ...
139s Unpacking libpath-utils1:s390x (0.6.2-2) ...
139s Selecting previously unselected package libref-array1:s390x.
139s Preparing to unpack .../23-libref-array1_0.6.2-2_s390x.deb ...
139s Unpacking libref-array1:s390x (0.6.2-2) ...
139s Selecting previously unselected package libini-config5:s390x.
139s Preparing to unpack .../24-libini-config5_0.6.2-2_s390x.deb ...
139s Unpacking libini-config5:s390x (0.6.2-2) ...
139s Selecting previously unselected package gssproxy.
139s Preparing to unpack .../25-gssproxy_0.9.2-2_s390x.deb ...
139s Unpacking gssproxy (0.9.2-2) ...
139s Selecting previously unselected package libapache2-mod-auth-gssapi.
139s Preparing to unpack .../26-libapache2-mod-auth-gssapi_1.6.4-3_s390x.deb ...
139s Unpacking libapache2-mod-auth-gssapi (1.6.4-3) ...
139s Selecting previously unselected package autopkgtest-satdep.
139s Preparing to unpack .../27-1-autopkgtest-satdep.deb ...
139s Unpacking autopkgtest-satdep (0) ...
139s Setting up libpath-utils1:s390x (0.6.2-2) ...
139s Setting up libbasicobjects0:s390x (0.6.2-2) ...
139s Setting up libapr1:s390x (1.7.2-3) ...
139s Setting up libgssrpc4:s390x (1.20.1-5build1) ...
139s Setting up krb5-config (2.7) ...
140s Setting up libevent-2.1-7:s390x (2.1.12-stable-9) ...
140s Setting up libcollection4:s390x (0.6.2-2) ...
140s Setting up liblua5.4-0:s390x (5.4.6-3) ...
140s Setting up libref-array1:s390x (0.6.2-2) ...
140s Setting up apache2-data (2.4.58-1ubuntu2) ...
140s Setting up libkadm5clnt-mit12:s390x (1.20.1-5build1) ...
140s Setting up libaprutil1:s390x (1.6.3-1ubuntu1) ...
140s Setting up libkdb5-10:s390x (1.20.1-5build1) ...
140s Setting up libini-config5:s390x (0.6.2-2) ...
140s Setting up libaprutil1-ldap:s390x (1.6.3-1ubuntu1) ...
140s Setting up libaprutil1-dbd-sqlite3:s390x (1.6.3-1ubuntu1) ...
140s Setting up libkadm5srv-mit12:s390x (1.20.1-5build1) ...
140s Setting up apache2-utils (2.4.58-1ubuntu2) ...
140s Setting up apache2-bin (2.4.58-1ubuntu2) ...
140s Setting up krb5-user (1.20.1-5build1) ...
140s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode
140s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode
140s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode
140s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode
140s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode
140s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode
140s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode
140s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode
140s Setting up libapache2-mod-auth-gssapi (1.6.4-3) ...
140s Package apache2 is not configured yet. Will defer actions by package libapache2-mod-auth-gssapi.
140s Setting up apache2 (2.4.58-1ubuntu2) ...
140s Enabling module mpm_event.
140s Enabling module authz_core.
140s Enabling module authz_host.
140s Enabling module authn_core.
140s Enabling module auth_basic.
140s Enabling module access_compat.
140s Enabling module authn_file.
140s Enabling module authz_user.
140s Enabling module alias.
140s Enabling module dir.
140s Enabling module autoindex.
140s Enabling module env.
140s Enabling module mime.
140s Enabling module negotiation.
140s Enabling module setenvif.
140s Enabling module filter.
140s Enabling module deflate.
140s Enabling module status.
141s Enabling module reqtimeout.
141s Enabling conf charset.
141s Enabling conf localized-error-pages.
141s Enabling conf other-vhosts-access-log.
141s Enabling conf security.
141s Enabling conf serve-cgi-bin.
141s Enabling site 000-default.
141s info: Executing deferred 'a2enmod auth_gssapi' for package libapache2-mod-auth-gssapi
141s Enabling module auth_gssapi.
142s Created symlink /etc/systemd/system/multi-user.target.wants/apache2.service → /usr/lib/systemd/system/apache2.service.

143s Created symlink /etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service → /usr/lib/systemd/system/apache-htcacheclean.service.

145s Setting up libverto-libevent1:s390x (0.3.1-1ubuntu5) ...
145s Setting up libverto1:s390x (0.3.1-1ubuntu5) ...
145s Setting up gssproxy (0.9.2-2) ...
145s Created symlink /etc/systemd/user/sockets.target.wants/gssuserproxy.socket → /usr/lib/systemd/user/gssuserproxy.socket.

145s Created symlink /etc/systemd/system/multi-user.target.wants/gssproxy.service → /usr/lib/systemd/system/gssproxy.service.

146s Setting up krb5-kdc (1.20.1-5build1) ...
146s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-kdc.service → /usr/lib/systemd/system/krb5-kdc.service.

147s Could not execute systemctl:  at /usr/bin/deb-systemd-invoke line 148.
147s Setting up krb5-admin-server (1.20.1-5build1) ...
148s Created symlink /etc/systemd/system/multi-user.target.wants/krb5-admin-server.service → /usr/lib/systemd/system/krb5-admin-server.service.

148s Setting up autopkgtest-satdep (0) ...
148s Processing triggers for libc-bin (2.39-0ubuntu2) ...
148s Processing triggers for ufw (0.36.2-5) ...
148s Processing triggers for man-db (2.12.0-3) ...
153s (Reading database ... 52635 files and directories currently installed.)
153s Removing autopkgtest-satdep (0) ...
154s autopkgtest [17:46:48]: test gssproxy-apache: [-----------------------
154s /usr/bin/ss
154s /usr/bin/id
154s /usr/bin/hostname
154s /usr/bin/dpkg
154s + command -v ss
154s + command -v id
154s + command -v hostname
154s + command -v dpkg
154s + dpkg -V krb5-kdc
154s + dpkg -V krb5-admin-server
154s + dpkg -V apache2
154s + dpkg -V libapache2-mod-auth-gssapi
154s + dpkg -V gssproxy
154s + dpkg -V curl
154s + service apache2 stop
155s + service krb5-kdc stop
156s + command -v systemctl
156s + service gssproxy stop
156s /usr/bin/systemctl
156s + export PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/sbin:/usr/sbin
156s + trap 
156s      set +e;
156s      kdestroy
156s      service apache2 stop
156s      service krb5-kdc stop
156s      for f in /tmp/out-*;
156s          do echo ----- BEGIN FILE $f -----
156s 	 cat $f
156s      done
156s      tail -v /var/log/apache2/error.log /var/log/apache2/access.log
156s      mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf
156s      mv /etc/krb5.conf-gssproxysave /etc/krb5.conf
156s      mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf
156s      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
156s      test -d /etc/systemd/system/apache2.service.d && rmdir /etc/systemd/system/apache2.service.d
156s      rmdir /var/www/html/gssproxy-autopkgtest
156s       0 INT QUIT ABRT PIPE TERM
156s + tail --pid=2258 -F /var/log/syslog
156s + id -un
157s + my_user=root
157s + hostname -f
157s 2024-03-21T17:46:43.077899+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: systemd-hostnamed.service: Deactivated successfully.
157s 2024-03-21T17:46:44.969487+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd-timesyncd[371]: Contacted time server 185.125.190.58:123 (ntp.ubuntu.com).
157s 2024-03-21T17:46:44.969918+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd-timesyncd[371]: Initial clock synchronization to Thu 2024-03-21 17:46:44.958064 UTC.
157s 2024-03-21T17:46:44.973933+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd-resolved[360]: Clock change detected. Flushing caches.
157s 2024-03-21T17:46:49.311285+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server...
157s 2024-03-21T17:46:49.371915+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully.
157s 2024-03-21T17:46:49.372027+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server.
157s 2024-03-21T17:46:50.935917+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopping gssproxy.service - GSSAPI Proxy Daemon...
157s 2024-03-21T17:46:50.939791+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: gssproxy.service: Deactivated successfully.
157s 2024-03-21T17:46:50.939942+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopped gssproxy.service - GSSAPI Proxy Daemon.
157s + my_hostname=adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal
157s + hostname -d
157s + my_domainname=novalocal
157s + test -f /etc/krb5kdc/kdc.conf-gssproxysave
157s + cp /etc/krb5kdc/kdc.conf /etc/krb5kdc/kdc.conf-gssproxysave
157s + cat
157s + test -f /etc/krb5.conf-gssproxysave
157s + cp /etc/krb5.conf /etc/krb5.conf-gssproxysave
157s + Initializing database '/var/lib/krb5kdc/gssproxy-principal' for realm 'GSSPROXY.EXAMPLE.ORG',
157s master key name 'K/M@GSSPROXY.EXAMPLE.ORG'
157s cat
157s + kdb5_util -P foo create -s
157s + kadmin.local addprinc -randkey HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal
157s + kadmin.local addprinc -pw bar root
157s + kadmin.local ktadd -k /etc/gssproxy/httpd.keytab HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal
157s Entry for principal HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab.
157s Entry for principal HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/gssproxy/httpd.keytab.
157s + service krb5-kdc start
157s 2024-03-21T17:46:51.868162+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Starting krb5-kdc.service - Kerberos 5 Key Distribution Center...
157s 2024-03-21T17:46:51.897521+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo (krb5kdc)[2308]: krb5-kdc.service: Referenced but unset environment variable evaluates to an empty string: DAEMON_ARGS
157s 2024-03-21T17:46:51.902636+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Started krb5-kdc.service - Kerberos 5 Key Distribution Center.
157s + i=0
157s + + ss -na
157s + grepgrep 0.0.0.0:17643
157s  LISTEN
158s tcp   LISTEN    0      5                                                     0.0.0.0:17643                     0.0.0.0:*           
158s + kdestroy
158s kdestroy: No credentials cache found while destroying cache
158s + echo bar
158s + kinit root
158s Password for root@GSSPROXY.EXAMPLE.ORG: 
158s + test -f /etc/gssproxy/gssproxy.conf-gssproxysave
158s + cp /etc/gssproxy/gssproxy.conf /etc/gssproxy/gssproxy.conf-gssproxysave
158s + cat
158s + cat
158s + command -v systemctl
158s + cat
158s + KRB5RCACHEDIR=/var/lib/gssproxy/rcache /usr/sbin/gssproxy -i --debug-level=2
158s + command -v systemctl
158s + mkdir -p /etc/systemd/system/apache2.service.d
158s + cat
158s + systemctl daemon-reload
158s /usr/bin/systemctl
158s /usr/bin/systemctl
158s 2024-03-21T17:46:52.738498+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Reloading requested from client PID 2336 ('systemctl') (unit session-1.scope)...
158s 2024-03-21T17:46:52.738864+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Reloading...
159s 2024-03-21T17:46:53.052332+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Reloading finished in 314 ms.
159s + service apache2 start
160s 2024-03-21T17:46:53.985187+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Starting apache2.service - The Apache HTTP Server...
160s + mkdir -p /var/www/html/gssproxy-autopkgtest
160s 2024-03-21T17:46:54.016151+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Started apache2.service - The Apache HTTP Server.
160s + echo Ossifrage
160s + curl --verbose --negotiate -u : http://adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal/gssproxy-autopkgtest/foo
160s + grep Ossifrage /tmp/out-curl-out
160s Ossifrage
160s + echo PASS: /tmp/autopkgtest.L2zEPf/build.CYJ/src/debian/tests/gssproxy-apache
160s PASS: /tmp/autopkgtest.L2zEPf/build.CYJ/src/debian/tests/gssproxy-apache
160s + exit 0
160s + set +e
160s + kdestroy
160s + service apache2 stop
161s 2024-03-21T17:46:55.167865+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopping apache2.service - The Apache HTTP Server...
161s 2024-03-21T17:46:55.229239+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: apache2.service: Deactivated successfully.
161s 2024-03-21T17:46:55.230381+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopped apache2.service - The Apache HTTP Server.
161s + service krb5-kdc stop
162s 2024-03-21T17:46:56.307058+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopping krb5-kdc.service - Kerberos 5 Key Distribution Center...
162s ----- BEGIN FILE /tmp/out-curl-err -----
162s 2024-03-21T17:46:56.312853+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: krb5-kdc.service: Deactivated successfully.
162s + echo ----- BEGIN FILE /tmp/out-curl-err -----
162s + cat /tmp/out-curl-err
162s * 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'
162s   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
162s                                  Dload  Upload   Total   Spent    Left  Speed
162s 
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0* Host adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal:80 was resolved.
162s * IPv6: (none)
162s * IPv4: 127.0.1.1
162s *   Trying 127.0.1.1:80...
162s * Connected to adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal (127.0.1.1) port 80
162s * Server auth using Negotiate with user ''
162s > GET /gssproxy-autopkgtest/foo HTTP/1.1
162s > Host: adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal
162s > Authorization: Negotiate YIIDTgYGKwYBBQUCoIIDQjCCAz6gDTALBgkqhkiG9xIBAgKiggMrBIIDJ2CCAyMGCSqGSIb3EgECAgEAboIDEjCCAw6gAwIBBaEDAgEOogcDBQAgAAAAo4ICHmGCAhowggIWoAMCAQWhFhsUR1NTUFJPWFkuRVhBTVBMRS5PUkeiXDBaoAMCAQOhUzBRGwRIVFRQG0lhZHQtbm9ibGUtczM5MHgtZ3NzcHJveHktMjAyNDAzMjEtMTc0NDEzLWp1anUtN2YyMjc1LXByb2QtcHJvcG8ubm92YWxvY2Fso4IBlzCCAZOgAwIBEqEDAgECooIBhQSCAYE6G1RAC1aeQqv64EFSGQ1TZcdpA9YEPUhPjtLUEAntVmvMMo+NWlWMFsQsioj2ypSCDAhLmWBr/DhEZcJKxALh9P4sBh2cYM8py3/gxemw7vRApvYDmt5ySaNDPi/gzktFyiUkv+8fsxvbmbVn+akixdfT9DvgRjYk2ZUveervhjbtrqV8iYAr5m4ocKelzXpEmiim4WxXshE+36zCbHTHs6oFbf25OaCUC9HZmtdrPW1ly9gGFfRUbP1r7djbCDH9hUUzvQ9CN1IgHP0q8fIQ28Awan63wac78GG6SY+zzlJQXzU/6VUGVubtH2Ontw5fOQ0Y9c3k6RGT6cvWgCsVGOFRBSDIYfIRgZC7jB5VZ7sodH3aCid4vujjKdAY+iuevtrDwaX5bSlRfpxVs7qOe2YJn79J1rTFAoY8bHjIx9wdlzD1PzYc1n3sMqHxhWkRqTnVbtDPrOw1m5n0FWY3f4k8W+glhvxkXEBEvD8iWEwWpYVl4xJyHBRUKudR+kSzpIHWMIHToAMCARKigcsEgcjaRASkHjUC3YBE4PILiWwBlxvZ3DpCYMdQM0v46uryLqzaqESv5Ep5BO3mT5gEOPY/zbevX/pNkAHJQArS6LSYrza6k5mL8V8BqUOneGmotJT9tzwvAEJF9oxwExlL/Aq7IRsft01QgxMdWTMHnw1qizGVC0ufPk/4hOQyQNYeZWgQ+i+TDmdmws9bJIuRiZYh0r9o4nNpnwR1G8IaIOhh29hjIX+wmfK9v5uednY1fd2m9zAHxjvKmmenMXAXIEeI4SLPkmRomA==
162s > User-Agent: curl/8.5.0
162s > Accept: */*
162s > 
162s < HTTP/1.1 200 OK
162s < Date: Thu, 21 Mar 2024 17:46:54 GMT
162s < Server: Apache/2.4.58 (Ubuntu)
162s < WWW-Authenticate: Negotiate oYG3MIG0oAMKAQChCwYJKoZIhvcSAQICooGfBIGcYIGZBgkqhkiG9xIBAgICAG+BiTCBhqADAgEFoQMCAQ+iejB4oAMCARKicQRvFQiXatFgLURBd4CKt2/lavDluvzjfUX2hZwS6vxR5L5Xf4rvltnlwsaSdeZp9x2I2/koaNG8y0OpNgvFKw4Ff9fzLyNt0IiWr3ct2NBvy7rk17G0oA0zy+Sg+OQa+RNZIpKhJdCGUv4bvj+ODFnl
162s < Last-Modified: Thu, 21 Mar 2024 17:46:54 GMT
162s < ETag: W/"a-6142f4af7ad2f"
162s < Accept-Ranges: bytes
162s < Content-Length: 10
162s < 
162s { [10 bytes data]
162s 
100    10  100    10    0     0    622      0 --:--:-- --:--:-- --:--:--   666
162s * Connection #0 to host adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal left intact
162s 2024-03-21T17:46:56.313062+00:00 adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo systemd[1]: Stopped krb5-kdc.service - Kerberos 5 Key Distribution Center.
162s + echo ----- BEGIN FILE /tmp/out-curl-out----- BEGIN FILE /tmp/out-curl-out -----
162s Ossifrage
162s ----- BEGIN FILE /tmp/out-gssproxy-err -----
162s  -----
162s + cat /tmp/out-curl-out
162s + echo ----- BEGIN FILE /tmp/out-gssproxy-err -----
162s + cat /tmp/out-gssproxy-err
162s + echo ----- BEGIN FILE /tmp/out-gssproxy-out -----
162s + cat /tmp/out-gssproxy-out
162s + tail -v /var/log/apache2/error.log /var/log/apache2/access.log
162s + mv /etc/krb5kdc/kdc.conf-gssproxysave /etc/krb5kdc/kdc.conf
162s + mv /etc/krb5.conf-gssproxysave /etc/krb5.conf
162s + mv /etc/gssproxy/gssproxy.conf-gssproxysave /etc/gssproxy/gssproxy.conf
162s + 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
162s rm: cannot remove '/var/lib/krb5kdc/gssproxy-stash'[2024/03/21 17:46:52]: Debug Level changed to 2
162s [2024/03/21 17:46:52]: Debug Level changed to 2
162s [2024/03/21 17:46:52]: Keytab /etc/krb5.keytab has no content (-1765328203)
162s [2024/03/21 17:46:52]: Service: nfs-server, Enckey: [ephemeral], Enctype: 18
162s [2024/03/21 17:46:52]: Service: HTTP, Keytab: /etc/gssproxy/httpd.keytab, Enctype: 18
162s [2024/03/21 17:46:52]: Kernel doesn't support GSS-Proxy (can't open /proc/net/rpc/use-gss-proxy: 2 (No such file or directory))
162s [2024/03/21 17:46:52]: Initialization complete.
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [2024/03/21 17:46:53]: Total received bytes: 4
162s [2024/03/21 17:46:53]: Idle for: 1711043213 seconds
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 56
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 112
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 168
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 224
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 280
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 336
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 392
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:53]: Client [2024/03/21 17:46:53]: (/usr/sbin/apache2) [2024/03/21 17:46:53]:  connected (fd = 11)[2024/03/21 17:46:53]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:53]: 
162s [CID 11][2024/03/21 17:46:53]: No matching service found
162s [2024/03/21 17:46:53]: Total received bytes: 448
162s [2024/03/21 17:46:53]: Idle for: 0 seconds
162s [2024/03/21 17:46:54]: Client [2024/03/21 17:46:54]: (/usr/sbin/apache2) [2024/03/21 17:46:54]:  connected (fd = 11)[2024/03/21 17:46:54]:  (pid = 2380) (uid = 0) (gid = 0)[2024/03/21 17:46:54]: 
162s [CID 11][2024/03/21 17:46:54]: No matching service found
162s [2024/03/21 17:46:54]: Total received bytes: 504
162s [2024/03/21 17:46:54]: Idle for: 1 seconds
162s [2024/03/21 17:46:54]: Client [2024/03/21 17:46:54]: (/usr/sbin/apache2) [2024/03/21 17:46:54]:  connected (fd = 11)[2024/03/21 17:46:54]:  (pid = 2383) (uid = 33) (gid = 33)[2024/03/21 17:46:54]: 
162s [CID 11][2024/03/21 17:46:54]: Connection matched service HTTP
162s [CID 11][2024/03/21 17:46:54]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null)
162s     GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [  ] } input_cred_handle: <Null> add_cred: 0 desired_name: <Null> time_req: 4294967295 desired_mechs: { { 1 2 840 113554 1 2 2 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 )
162s [2024/03/21 17:46:54]: Total received bytes: 624
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s     GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [  ] } output_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .VP.......yd..T.... ] 0 } )
162s [2024/03/21 17:46:54]: Total sent bytes: 1044
162s [2024/03/21 17:46:54]: Total received bytes: 1700
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s [CID 11][2024/03/21 17:46:54]: Connection matched service HTTP
162s [CID 11][2024/03/21 17:46:54]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null)
162s     GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [  ] } input_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .VP.......yd..T.... ] 0 } add_cred: 0 desired_name: <Null> time_req: 4294967295 desired_mechs: { { 1 3 6 1 5 2 5 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 )
162s     GSSX_RES_ACQUIRE_CRED( status: { 458752 <None> 0 "No credentials were supplied, or the credentials were unavailable or inaccessible" "" [  ] } output_cred_handle: <Null> )
162s [2024/03/21 17:46:54]: Total sent bytes: 1200
162s [2024/03/21 17:46:54]: Total received bytes: 1704
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s [2024/03/21 17:46:54]: Total received bytes: 1756
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s [CID 11][2024/03/21 17:46:54]: Connection matched service HTTP
162s [CID 11][2024/03/21 17:46:54]: gp_rpc_execute: executing 1 (GSSX_INDICATE_MECHS) for service "HTTP", euid: 33,socket: (null)
162s     GSSX_ARG_INDICATE_MECHS( call_ctx: { "" [  ] } )
162s     GSSX_RES_INDICATE_MECHS( status: { 0 <None> 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: [ ] )
162s [2024/03/21 17:46:54]: Total sent bytes: 6572
162s [2024/03/21 17:46:54]: Total received bytes: 2836
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s [CID 11][2024/03/21 17:46:54]: Connection matched service HTTP
162s [CID 11][2024/03/21 17:46:54]: gp_rpc_execute: executing 6 (GSSX_ACQUIRE_CRED) for service "HTTP", euid: 33,socket: (null)
162s     GSSX_ARG_ACQUIRE_CRED( call_ctx: { "" [  ] } input_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .VP.......yd..T.... ] 0 } add_cred: 0 desired_name: <Null> time_req: 4294967295 desired_mechs: { { 1 2 840 113554 1 2 2 } } cred_usage: ACCEPT initiator_time_req: 0 acceptor_time_req: 0 )
162s     GSSX_RES_ACQUIRE_CRED( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [  ] } output_cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .VP.......yd..T.... ] 0 } )
162s [2024/03/21 17:46:54]: Total sent bytes: 7616
162s [2024/03/21 17:46:54]: Total received bytes: 4684
162s [2024/03/21 17:46:54]: Idle for: 0 seconds
162s [CID 11][2024/03/21 17:46:54]: Connection matched service HTTP
162s [CID 11][2024/03/21 17:46:54]: gp_rpc_execute: executing 9 (GSSX_ACCEPT_SEC_CONTEXT) for service "HTTP", euid: 33,socket: (null)
162s     GSSX_ARG_ACCEPT_SEC_CONTEXT( call_ctx: { "" [  ] } context_handle: <Null> cred_handle: { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" [ { "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" { 1 2 840 113554 1 2 2 } ACCEPT 0 4294967295 } ] [ .VP.......yd..T.... ] 0 } input_token: [ ........H.......... ] input_cb: <Null> ret_deleg_cred: 1 )
162s     GSSX_RES_ACCEPT_SEC_CONTEXT( status: { 0 { 1 2 840 113554 1 2 2 } 0 "" "" [  ] } context_handle: { [ ......H............ ] [  ] 0 { 1 2 840 113554 1 2 2 } "root@GSSPROXY.EXAMPLE.ORG" "HTTP/adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-propo.novalocal@GSSPROXY.EXAMPLE.ORG" 86697 438 0 1 } output_token: [ .......H........... ] delegated_cred_handle: <Null> )
162s [2024/03/21 17:46:54]: Total sent bytes: 9908
162s [2024/03/21 17:46:55]: Client [2024/03/21 17:46:55]: (/usr/sbin/apache2) [2024/03/21 17:46:55]:  connected (fd = 12)[2024/03/21 17:46:55]:  (pid = 2452) (uid = 0) (gid = 0)[2024/03/21 17:46:55]: 
162s [2024/03/21 17:46:55]: Total received bytes: 4688
162s [2024/03/21 17:46:55]: Idle for: 1 seconds
162s [2024/03/21 17:46:55]: Total received bytes: 4740
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [CID 12][2024/03/21 17:46:55]: No matching service found
162s [2024/03/21 17:46:55]: Client [2024/03/21 17:46:55]: (/usr/sbin/apache2) [2024/03/21 17:46:55]:  connected (fd = 12)[2024/03/21 17:46:55]:  (pid = 2452) (uid = 0) (gid = 0)[2024/03/21 17:46:55]: 
162s [2024/03/21 17:46:55]: Total received bytes: 4744
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [2024/03/21 17:46:55]: Total received bytes: 4796
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [CID 12][2024/03/21 17:46:55]: No matching service found
162s [2024/03/21 17:46:55]: Client [2024/03/21 17:46:55]: (/usr/sbin/apache2) [2024/03/21 17:46:55]:  connected (fd = 12)[2024/03/21 17:46:55]:  (pid = 2452) (uid = 0) (gid = 0)[2024/03/21 17:46:55]: 
162s [2024/03/21 17:46:55]: Total received bytes: 4800
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [CID 12][2024/03/21 17:46:55]: No matching service found
162s [2024/03/21 17:46:55]: Total received bytes: 4852
162s [2024/03/21 : No such file or directory
162s + test -d /etc/systemd/system/apache2.service.d
162s + rmdir /etc/systemd/system/apache2.service.d
162s + rmdir /var/www/html/gssproxy-autopkgtest
162s /tmp/autopkgtest.L2zEPf/wrapper.sh: Killing leaked background processes: 2290 
162s     PID TTY      STAT   TIME COMMAND
162s    2290 ?        S      0:00 tail --pid=2258 -F /var/log/syslog
162s 17:46:55]: Idle for: 0 seconds
162s [2024/03/21 17:46:55]: Client [2024/03/21 17:46:55]: (/usr/sbin/apache2) [2024/03/21 17:46:55]:  connected (fd = 12)[2024/03/21 17:46:55]:  (pid = 2452) (uid = 0) (gid = 0)[2024/03/21 17:46:55]: 
162s [2024/03/21 17:46:55]: Total received bytes: 4856
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [2024/03/21 17:46:55]: Total received bytes: 4908
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [CID 12][2024/03/21 17:46:55]: No matching service found
162s [2024/03/21 17:46:55]: Client [2024/03/21 17:46:55]: (/usr/sbin/apache2) [2024/03/21 17:46:55]:  connected (fd = 12)[2024/03/21 17:46:55]:  (pid = 2452) (uid = 0) (gid = 0)[2024/03/21 17:46:55]: 
162s [2024/03/21 17:46:55]: Total received bytes: 4912
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s [CID 12][2024/03/21 17:46:55]: No matching service found
162s [2024/03/21 17:46:55]: Total received bytes: 4964
162s [2024/03/21 17:46:55]: Idle for: 0 seconds
162s ----- BEGIN FILE /tmp/out-gssproxy-out -----
162s ==> /var/log/apache2/error.log <==
162s [Thu Mar 21 17:46:35.479700 2024] [mpm_event:notice] [pid 1711:tid 4396776642432] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations
162s [Thu Mar 21 17:46:35.479823 2024] [core:notice] [pid 1711:tid 4396776642432] AH00094: Command line: '/usr/sbin/apache2'
162s [Thu Mar 21 17:46:49.329880 2024] [mpm_event:notice] [pid 1711:tid 4396776642432] AH00492: caught SIGWINCH, shutting down gracefully
162s [Thu Mar 21 17:46:54.030075 2024] [mpm_event:notice] [pid 2381:tid 4396247635840] AH00489: Apache/2.4.58 (Ubuntu) mod_auth_gssapi/1.6.4 configured -- resuming normal operations
162s [Thu Mar 21 17:46:54.030280 2024] [core:notice] [pid 2381:tid 4396247635840] AH00094: Command line: '/usr/sbin/apache2'
162s [Thu Mar 21 17:46:55.195645 2024] [mpm_event:notice] [pid 2381:tid 4396247635840] AH00492: caught SIGWINCH, shutting down gracefully
162s 
162s ==> /var/log/apache2/access.log <==
162s 127.0.0.1 - root@GSSPROXY.EXAMPLE.ORG [21/Mar/2024:17:46:54 +0000] "GET /gssproxy-autopkgtest/foo HTTP/1.1" 200 491 "-" "curl/8.5.0"
162s autopkgtest [17:46:56]: test gssproxy-apache: -----------------------]
163s autopkgtest [17:46:57]: test gssproxy-apache:  - - - - - - - - - - results - - - - - - - - - -
163s gssproxy-apache      PASS
164s autopkgtest [17:46:58]: @@@@@@@@@@@@@@@@@@@@ summary
164s gssproxy-apache      PASS
174s Creating nova instance adt-noble-s390x-gssproxy-20240321-174413-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...