0s autopkgtest [08:24:35]: starting date and time: 2025-07-11 08:24:35+0000 0s autopkgtest [08:24:35]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [08:24:35]: host juju-7f2275-prod-proposed-migration-environment-21; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.obdajwxh/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade stenographer --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-9ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@bos03-s390x-10.secgroup --name adt-questing-s390x-stenographer-20250711-080328-juju-7f2275-prod-proposed-migration-environment-21-092f8fab-05b9-4970-a8a8-32eab8d53e34 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-21 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-questing-s390x-stenographer-20250711-080328-juju-7f2275-prod-proposed-migration-environment-21-092f8fab-05b9-4970-a8a8-32eab8d53e34 from image adt/ubuntu-questing-s390x-server-20250711.img (UUID c5c71746-41c7-48a3-a212-e68c6cc91b9b)... 52s autopkgtest [08:25:27]: testbed dpkg architecture: s390x 52s autopkgtest [08:25:27]: testbed apt version: 3.1.3 53s autopkgtest [08:25:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup 53s autopkgtest [08:25:28]: testbed release detected to be: None 53s autopkgtest [08:25:28]: updating testbed package index (apt update) 54s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 54s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 54s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 54s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 54s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 54s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [179 kB] 54s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [37.7 kB] 54s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [51.7 kB] 54s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [158 kB] 54s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [4452 B] 54s Fetched 696 kB in 1s (858 kB/s) 55s Reading package lists... 56s autopkgtest [08:25:31]: upgrading testbed (apt dist-upgrade and autopurge) 56s Reading package lists... 56s Building dependency tree... 56s Reading state information... 56s Calculating upgrade... 56s The following packages will be upgraded: 56s libc-bin libc-dev-bin libc6 libc6-dev locales 56s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s Need to get 9522 kB of archives. 56s After this operation, 15.4 kB of additional disk space will be used. 56s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libc6-dev s390x 2.41-9ubuntu1 [1678 kB] 57s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libc-dev-bin s390x 2.41-9ubuntu1 [23.0 kB] 57s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libc6 s390x 2.41-9ubuntu1 [2896 kB] 57s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libc-bin s390x 2.41-9ubuntu1 [672 kB] 57s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x locales all 2.41-9ubuntu1 [4253 kB] 58s Preconfiguring packages ... 58s Fetched 9522 kB in 2s (4933 kB/s) 58s (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 ... 82585 files and directories currently installed.) 58s Preparing to unpack .../libc6-dev_2.41-9ubuntu1_s390x.deb ... 58s Unpacking libc6-dev:s390x (2.41-9ubuntu1) over (2.41-6ubuntu2) ... 58s Preparing to unpack .../libc-dev-bin_2.41-9ubuntu1_s390x.deb ... 58s Unpacking libc-dev-bin (2.41-9ubuntu1) over (2.41-6ubuntu2) ... 58s Preparing to unpack .../libc6_2.41-9ubuntu1_s390x.deb ... 58s Unpacking libc6:s390x (2.41-9ubuntu1) over (2.41-6ubuntu2) ... 59s Setting up libc6:s390x (2.41-9ubuntu1) ... 59s (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 ... 82585 files and directories currently installed.) 59s Preparing to unpack .../libc-bin_2.41-9ubuntu1_s390x.deb ... 59s Unpacking libc-bin (2.41-9ubuntu1) over (2.41-6ubuntu2) ... 59s Setting up libc-bin (2.41-9ubuntu1) ... 59s (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 ... 82585 files and directories currently installed.) 59s Preparing to unpack .../locales_2.41-9ubuntu1_all.deb ... 59s Unpacking locales (2.41-9ubuntu1) over (2.41-6ubuntu2) ... 59s Setting up locales (2.41-9ubuntu1) ... 59s Generating locales (this might take a while)... 60s en_US.UTF-8... done 60s Generation complete. 60s Setting up libc-dev-bin (2.41-9ubuntu1) ... 60s Setting up libc6-dev:s390x (2.41-9ubuntu1) ... 60s Processing triggers for man-db (2.13.1-1) ... 61s Processing triggers for systemd (257.7-1ubuntu1) ... 62s Reading package lists... 62s Building dependency tree... 62s Reading state information... 62s Solving dependencies... 62s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 64s autopkgtest [08:25:39]: testbed running kernel: Linux 6.15.0-4-generic #4-Ubuntu SMP Fri Jul 4 13:27:29 UTC 2025 64s autopkgtest [08:25:39]: @@@@@@@@@@@@@@@@@@@@ apt-source stenographer 67s Get:1 http://ftpmaster.internal/ubuntu questing/universe stenographer 1.0.1-7 (dsc) [2753 B] 67s Get:2 http://ftpmaster.internal/ubuntu questing/universe stenographer 1.0.1-7 (tar) [149 kB] 67s Get:3 http://ftpmaster.internal/ubuntu questing/universe stenographer 1.0.1-7 (diff) [15.1 kB] 67s gpgv: Signature made Wed Aug 7 05:05:27 2024 UTC 67s gpgv: using RSA key 5B34BA5AAB5507E903426E85E8D37AE2F09F4872 67s gpgv: Can't check signature: No public key 67s dpkg-source: warning: cannot verify inline signature for ./stenographer_1.0.1-7.dsc: no acceptable signature found 67s autopkgtest [08:25:42]: testing package stenographer version 1.0.1-7 67s autopkgtest [08:25:42]: build not needed 68s autopkgtest [08:25:43]: test run-example: preparing testbed 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 69s Solving dependencies... 69s The following NEW packages will be installed: 69s libleveldb1d libsnappy1v5 net-tools stenographer stenographer-client 69s stenographer-common 69s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 69s Need to get 4389 kB of archives. 69s After this operation, 15.3 MB of additional disk space will be used. 69s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x libsnappy1v5 s390x 1.2.2-1 [33.0 kB] 69s Get:2 http://ftpmaster.internal/ubuntu questing/universe s390x libleveldb1d s390x 1.23-5build1 [134 kB] 69s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x net-tools s390x 2.10-1.3ubuntu1 [205 kB] 69s Get:4 http://ftpmaster.internal/ubuntu questing/universe s390x stenographer-common all 1.0.1-7 [21.0 kB] 69s Get:5 http://ftpmaster.internal/ubuntu questing/universe s390x stenographer s390x 1.0.1-7 [3990 kB] 70s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x stenographer-client all 1.0.1-7 [5288 B] 70s Fetched 4389 kB in 1s (3983 kB/s) 70s Selecting previously unselected package libsnappy1v5:s390x. 70s (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 ... 82585 files and directories currently installed.) 70s Preparing to unpack .../0-libsnappy1v5_1.2.2-1_s390x.deb ... 70s Unpacking libsnappy1v5:s390x (1.2.2-1) ... 70s Selecting previously unselected package libleveldb1d:s390x. 70s Preparing to unpack .../1-libleveldb1d_1.23-5build1_s390x.deb ... 70s Unpacking libleveldb1d:s390x (1.23-5build1) ... 70s Selecting previously unselected package net-tools. 70s Preparing to unpack .../2-net-tools_2.10-1.3ubuntu1_s390x.deb ... 70s Unpacking net-tools (2.10-1.3ubuntu1) ... 70s Selecting previously unselected package stenographer-common. 70s Preparing to unpack .../3-stenographer-common_1.0.1-7_all.deb ... 70s Unpacking stenographer-common (1.0.1-7) ... 70s Selecting previously unselected package stenographer. 70s Preparing to unpack .../4-stenographer_1.0.1-7_s390x.deb ... 70s Unpacking stenographer (1.0.1-7) ... 70s Selecting previously unselected package stenographer-client. 70s Preparing to unpack .../5-stenographer-client_1.0.1-7_all.deb ... 70s Unpacking stenographer-client (1.0.1-7) ... 70s Setting up net-tools (2.10-1.3ubuntu1) ... 70s Setting up stenographer-common (1.0.1-7) ... 70s Generating CA state 73s Generating key/cert for 'client' 73s Generating key/cert for 'server' 75s Warning: Ignoring -days without -x509; not generating a certificate 75s Using configuration from /tmp/stenossl.lo9qvjTT4B0z 75s Check that the request matches the signature 75s Signature ok 75s The Subject's Distinguished Name is as follows 75s countryName :PRINTABLE:'XX' 75s organizationName :ASN.1 12:'Stenographer' 75s commonName :ASN.1 12:'127.0.0.1_client' 75s Certificate is to be certified until Nov 25 08:25:48 2052 GMT (9999 days) 75s 75s Write out database with 1 new entries 75s Database updated 75s Warning: Ignoring -days without -x509; not generating a certificate 75s Using configuration from /tmp/stenossl.lo9qvjTT4B0z 75s Check that the request matches the signature 75s Signature ok 75s The Subject's Distinguished Name is as follows 75s countryName :PRINTABLE:'XX' 75s organizationName :ASN.1 12:'Stenographer' 75s commonName :ASN.1 12:'127.0.0.1' 75s Certificate is to be certified until Nov 25 08:25:50 2052 GMT (9999 days) 75s 75s Write out database with 1 new entries 75s Database updated 75s Setting up stenographer-client (1.0.1-7) ... 75s Setting up libsnappy1v5:s390x (1.2.2-1) ... 75s Setting up libleveldb1d:s390x (1.23-5build1) ... 75s Setting up stenographer (1.0.1-7) ... 75s setcap on /usr/sbin/stenotype worked. 75s Processing triggers for man-db (2.13.1-1) ... 76s Processing triggers for libc-bin (2.41-9ubuntu1) ... 77s autopkgtest [08:25:52]: test run-example: [----------------------- 77s Filesystem info: 77s Filesystem Type Size Used Avail Use% Mounted on 77s /dev/vda1 ext4 20G 1.9G 18G 10% / 77s Kernel IP routing table 77s Destination Gateway Genmask Flags Metric Ref Use Iface 77s 0.0.0.0 10.145.232.1 0.0.0.0 UG 100 0 0 enc1 77s 10.145.232.0 0.0.0.0 255.255.255.0 U 100 0 0 enc1 77s 10.145.232.1 0.0.0.0 255.255.255.255 UH 100 0 0 enc1 77s 10.145.232.2 0.0.0.0 255.255.255.255 UH 100 0 0 enc1 77s 91.189.91.131 10.145.232.1 255.255.255.255 UGH 100 0 0 enc1 77s 91.189.91.132 10.145.232.1 255.255.255.255 UGH 100 0 0 enc1 77s 169.254.169.254 10.145.232.2 255.255.255.255 UGH 100 0 0 enc1 77s Using interface enc1 97s ● stenographer.service - packet capture to disk 97s Loaded: loaded (/usr/lib/systemd/system/stenographer.service; disabled; preset: enabled) 97s Active: active (running) since Fri 2025-07-11 08:25:52 UTC; 20s ago 97s Invocation: c06e05731aa547dabddcea642d72ff3e 97s Docs: https://github.com/google/stenographer 97s https://github.com/google/stenographer/blob/master/DESIGN.md 97s https://github.com/google/stenographer/blob/master/INSTALL.md 97s Main PID: 2694 (stenographer) 97s Tasks: 13 (limit: 4766) 97s Memory: 9.4M (peak: 9.8M) 97s CPU: 110ms 97s CGroup: /system.slice/stenographer.service 97s ├─2694 /usr/sbin/stenographer 97s └─2700 /usr/sbin/stenotype --blocks=256 --seccomp=none --threads=1 --dir=/tmp/stenographer1875308434 --iface=enc1 97s 97s Jul 11 08:25:52 autopkgtest systemd[1]: Started stenographer.service - packet capture to disk. 97s active 97s % Total % Received % Xferd Average Speed Time Time Time Current 97s Dload Upload Total Spent Left Speed 98s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 271 100 271 0 0 761 0 --:--:-- --:--:-- --:--:-- 761 100 271 100 271 0 0 761 0 --:--:-- --:--:-- --:--:-- 761 278s ● stenographer.service - packet capture to disk 278s Loaded: loaded (/usr/lib/systemd/system/stenographer.service; disabled; preset: enabled) 278s Active: active (running) since Fri 2025-07-11 08:25:52 UTC; 3min 20s ago 278s Invocation: c06e05731aa547dabddcea642d72ff3e 278s Docs: https://github.com/google/stenographer 278s https://github.com/google/stenographer/blob/master/DESIGN.md 278s https://github.com/google/stenographer/blob/master/INSTALL.md 278s Main PID: 2694 (stenographer) 278s Tasks: 13 (limit: 4766) 278s Memory: 9.5M (peak: 10M) 278s CPU: 120ms 278s CGroup: /system.slice/stenographer.service 278s ├─2694 /usr/sbin/stenographer 278s └─2700 /usr/sbin/stenotype --blocks=256 --seccomp=none --threads=1 --dir=/tmp/stenographer1875308434 --iface=enc1 278s 278s Jul 11 08:25:52 autopkgtest systemd[1]: Started stenographer.service - packet capture to disk. 278s tcp 0 0 127.0.0.1:1234 0.0.0.0:* LISTEN 2694/stenographer 278s Running stenographer query 'after 5m ago', piping to 'tcpdump ' 278s curl: (58) could not load PEM client certificate from /etc/stenographer/certs/client_cert.pem, OpenSSL error error:8000000D:system library::Permission denied, (no key found, wrong pass phrase, or wrong file format?) 278s tcpdump: truncated dump file; tried to read 4 file header bytes, only got 0 278s autopkgtest [08:29:13]: test run-example: -----------------------] 278s autopkgtest [08:29:13]: test run-example: - - - - - - - - - - results - - - - - - - - - - 278s run-example FAIL non-zero exit status 1 279s autopkgtest [08:29:14]: @@@@@@@@@@@@@@@@@@@@ summary 279s run-example FAIL non-zero exit status 1