0s autopkgtest [15:24:14]: starting date and time: 2024-03-21 15:24:14+0000 0s autopkgtest [15:24:14]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [15:24:14]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.v9hu7top/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:openssl --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssl/3.0.13-0ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-18.secgroup --name adt-noble-s390x-stunnel4-20240321-152414-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 102s autopkgtest [15:25:56]: testbed dpkg architecture: s390x 102s autopkgtest [15:25:56]: testbed apt version: 2.7.12 102s autopkgtest [15:25:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 103s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 104s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 104s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 104s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3775 kB] 105s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 105s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [662 kB] 105s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 105s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 105s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 105s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3985 kB] 105s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 105s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 105s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 109s Fetched 9151 kB in 4s (2171 kB/s) 109s Reading package lists... 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 115s Calculating upgrade... 115s The following packages will be REMOVED: 115s libssl3 115s The following NEW packages will be installed: 115s libssl3t64 115s The following packages will be upgraded: 115s debianutils openssl 115s 2 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 115s Need to get 2775 kB of archives. 115s After this operation, 240 kB of additional disk space will be used. 115s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 115s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 116s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 116s Fetched 2775 kB in 1s (2974 kB/s) 117s (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.) 117s Preparing to unpack .../debianutils_5.17_s390x.deb ... 117s Unpacking debianutils (5.17) over (5.16) ... 117s Setting up debianutils (5.17) ... 117s (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.) 117s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 117s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 117s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 117s wget depends on libssl3 (>= 3.0.0). 117s tnftp depends on libssl3 (>= 3.0.0). 117s tcpdump depends on libssl3 (>= 3.0.0). 117s systemd-resolved depends on libssl3 (>= 3.0.0). 117s systemd depends on libssl3 (>= 3.0.0). 117s sudo depends on libssl3 (>= 3.0.0). 117s s390-tools depends on libssl3 (>= 3.0.0). 117s rsync depends on libssl3 (>= 3.0.0). 117s python3-cryptography depends on libssl3 (>= 3.0.0). 117s openssh-server depends on libssl3 (>= 3.0.10). 117s openssh-client depends on libssl3 (>= 3.0.10). 117s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 117s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 117s libssh-4:s390x depends on libssl3 (>= 3.0.0). 117s libsasl2-modules:s390x depends on libssl3 (>= 3.0.0). 117s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 117s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 117s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 117s libnvme1 depends on libssl3 (>= 3.0.0). 117s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 117s libkmod2:s390x depends on libssl3 (>= 3.0.0). 117s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 117s libcurl4:s390x depends on libssl3 (>= 3.0.0). 117s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 117s kmod depends on libssl3 (>= 3.0.0). 117s dhcpcd-base depends on libssl3 (>= 3.0.0). 117s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 117s 117s (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.) 117s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 117s Selecting previously unselected package libssl3t64:s390x. 117s (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 ... 52160 files and directories currently installed.) 117s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 117s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 117s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 117s Setting up openssl (3.0.13-0ubuntu2) ... 117s Processing triggers for man-db (2.12.0-3) ... 119s Processing triggers for libc-bin (2.39-0ubuntu2) ... 119s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Unknown architecture, assuming PC-style ttyS0 121s sh: Attempting to set up Debian/Ubuntu apt sources automatically 121s sh: Distribution appears to be Ubuntu 122s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s eatmydata is already the newest version (131-1). 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s dbus is already the newest version (1.14.10-4ubuntu1). 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s rng-tools-debian is already the newest version (2.4). 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 125s The following packages will be REMOVED: 125s cloud-init* python3-configobj* python3-debconf* 125s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 125s After this operation, 3252 kB disk space will be freed. 125s (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 ... 52173 files and directories currently installed.) 125s Removing cloud-init (24.1.1-0ubuntu1) ... 126s Removing python3-configobj (5.0.8-3) ... 126s Removing python3-debconf (1.5.86) ... 126s Processing triggers for man-db (2.12.0-3) ... 127s (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 ... 51784 files and directories currently installed.) 127s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 127s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 127s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 127s invoke-rc.d: policy-rc.d denied execution of try-restart. 128s Reading package lists... 128s Building dependency tree... 128s Reading state information... 128s linux-generic is already the newest version (6.8.0-11.11+1). 128s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 129s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 129s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 129s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 132s Reading package lists... 132s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s Calculating upgrade... 133s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s autopkgtest [15:26:28]: rebooting testbed after setup commands that affected boot 163s autopkgtest [15:26:57]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 166s autopkgtest [15:27:00]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 168s Get:1 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (dsc) [2581 B] 168s Get:2 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (tar) [883 kB] 168s Get:3 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (asc) [963 B] 168s Get:4 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (diff) [53.2 kB] 168s gpgv: Signature made Sun Sep 10 18:42:44 2023 UTC 168s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 168s gpgv: issuer "roam@debian.org" 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.70-2.dsc: no acceptable signature found 168s autopkgtest [15:27:02]: testing package stunnel4 version 3:5.70-2 169s autopkgtest [15:27:03]: build not needed 169s autopkgtest [15:27:03]: test debian-python: preparing testbed 177s Reading package lists... 177s Building dependency tree... 177s Reading state information... 178s Starting pkgProblemResolver with broken count: 0 178s Starting 2 pkgProblemResolver with broken count: 0 178s Done 178s The following additional packages will be installed: 178s python3-all stunnel4 178s Suggested packages: 178s logcheck-database 178s The following NEW packages will be installed: 178s autopkgtest-satdep python3-all stunnel4 178s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 178s Need to get 220 kB/221 kB of archives. 178s After this operation, 579 kB of additional disk space will be used. 178s Get:1 /tmp/autopkgtest.SckAzA/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [716 B] 179s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x stunnel4 s390x 3:5.70-2 [219 kB] 179s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x python3-all s390x 3.12.1-0ubuntu2 [908 B] 180s Fetched 220 kB in 1s (397 kB/s) 180s Selecting previously unselected package stunnel4. 180s (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 ... 51729 files and directories currently installed.) 180s Preparing to unpack .../stunnel4_3%3a5.70-2_s390x.deb ... 180s Unpacking stunnel4 (3:5.70-2) ... 180s Selecting previously unselected package python3-all. 180s Preparing to unpack .../python3-all_3.12.1-0ubuntu2_s390x.deb ... 180s Unpacking python3-all (3.12.1-0ubuntu2) ... 180s Selecting previously unselected package autopkgtest-satdep. 180s Preparing to unpack .../1-autopkgtest-satdep.deb ... 180s Unpacking autopkgtest-satdep (0) ... 180s Setting up stunnel4 (3:5.70-2) ... 180s Creating group 'stunnel4' with GID 988. 180s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 181s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 181s Setting up python3-all (3.12.1-0ubuntu2) ... 181s Setting up autopkgtest-satdep (0) ... 181s Processing triggers for man-db (2.12.0-3) ... 185s (Reading database ... 51774 files and directories currently installed.) 186s Removing autopkgtest-satdep (0) ... 187s autopkgtest [15:27:20]: test debian-python: set -e; for py in $(py3versions -i); do printf -- '\n\n====== %s\n\n' "$py"; env PYTHONPATH="$(pwd)/debian/tests/python" "$py" -B -u -m struntime --certdir "$(pwd)/debian/tests/certs"; done 187s autopkgtest [15:27:20]: test debian-python: [----------------------- 187s 187s 187s ====== python3.12 187s 187s Using /tmp/struntime.rab511ek as a temporary directory 187s Trying to obtain the version of /usr/bin/stunnel4 187s Started `/usr/bin/stunnel4 -version` as process 1284 187s Got stunnel version 5.70 187s [main] Starting to do things 187s [main] Awaiting the 'listener started' event 187s [find_listening_port] Looking for a port to listen on 187s [find_listening_port] Trying localhost port 6502 187s [listener] Telling the main thread about [::1]:6502 187s [listener] Awaiting client connections... 187s [main] Apparently we are listening on [::1]:6502 187s [main] Testing cleartext connections 187s [test_connect [::1]:6502 0] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 1] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 2] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 3] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 4] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 5] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 6] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 7] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 8] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 9] Trying port 6502 encrypted False 187s [test_connect [::1]:6502 2] Opening an unencrypted connection 187s [listener] A client connected from somewhere 187s [[::1]:55124] New connection 187s [[::1]:55124] Telling the main thread 187s [[::1]:55124] Let them tell us something... 187s [test_connect [::1]:6502 2] Connected to the server: [::1]:55124 187s [test_connect [::1]:6502 1] Opening an unencrypted connection 187s [test_connect [::1]:6502 4] Opening an unencrypted connection 187s [listener] A client connected from somewhere 187s [[::1]:55130] New connection 187s [[::1]:55130] Telling the main thread 187s [[::1]:55130] Let them tell us something... 187s [listener] A client connected from somewhere 187s [[::1]:55144] New connection 187s [[::1]:55144] Telling the main thread 187s [[::1]:55144] Let them tell us something... 187s [test_connect [::1]:6502 1] Connected to the server: [::1]:55130 187s [test_connect [::1]:6502 4] Connected to the server: [::1]:55144 187s [test_connect [::1]:6502 9] Opening an unencrypted connection 187s [listener] A client connected from somewhere 187s [[::1]:55150] New connection 187s [[::1]:55150] Telling the main thread 187s [[::1]:55150] Let them tell us something... 187s [test_connect [::1]:6502 9] Connected to the server: [::1]:55150 187s [test_connect [::1]:6502 7] Opening an unencrypted connection 187s [listener] A client connected from somewhere 187s [[::1]:55160] New connection 187s [[::1]:55160] Telling the main thread 187s [[::1]:55160] Let them tell us something... 187s [test_connect [::1]:6502 7] Connected to the server: [::1]:55160 187s [test_connect [::1]:6502 8] Opening an unencrypted connection 187s [listener] A client connected from somewhere 187s [[::1]:55164] New connection 187s [[::1]:55164] Telling the main thread 187s [[::1]:55164] Let them tell us something... 187s [test_connect [::1]:6502 8] Connected to the server: [::1]:55164 188s [test_connect [::1]:6502 3] Opening an unencrypted connection 188s [test_connect [::1]:6502 6] Opening an unencrypted connection 188s [listener] A client connected from somewhere 188s [[::1]:55172] New connection 188s [[::1]:55172] Telling the main thread 188s [[::1]:55172] Let them tell us something... 188s [listener] A client connected from somewhere 188s [[::1]:55180] New connection 188s [[::1]:55180] Telling the main thread 188s [[::1]:55180] Let them tell us something... 188s [test_connect [::1]:6502 3] Connected to the server: [::1]:55172 188s [test_connect [::1]:6502 6] Connected to the server: [::1]:55180 188s [test_connect [::1]:6502 9] Sending something... 188s [test_connect [::1]:6502 9] Waiting for the main thread to figure it out 188s [[::1]:55150] Telling the main thread about b'Hello 9\n' 188s [[::1]:55150] Writing something back... 188s [[::1]:55150] Telling the main thread about b'There!\n' 188s [[::1]:55150] Closing down the writer socket 188s [test_connect [::1]:6502 9] The listener acknowledged [::1]:55150 188s [test_connect [::1]:6502 9] Waiting for the server to receive it... 188s [test_connect [::1]:6502 9] Waiting for the server to send something 188s [test_connect [::1]:6502 9] Trying to receive the actual data 188s [test_connect [::1]:6502 9] Waiting for the server to close the connection 188s [[::1]:55150] Telling the main thread done 188s [test_connect [::1]:6502 9] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 9] Closing our writer socket, too 188s [test_connect [::1]:6502 9] Letting the main thread know we're done 188s [test_connect [::1]:6502 9] Done 188s [process_connections] Fetching task plain-9 188s [process_connections] Waiting for task plain-9 188s [process_connections] Done with task plain-9 188s [test_connect [::1]:6502 1] Sending something... 188s [test_connect [::1]:6502 1] Waiting for the main thread to figure it out 188s [[::1]:55130] Telling the main thread about b'Hello 1\n' 188s [[::1]:55130] Writing something back... 188s [[::1]:55130] Telling the main thread about b'There!\n' 188s [[::1]:55130] Closing down the writer socket 188s [test_connect [::1]:6502 1] The listener acknowledged [::1]:55130 188s [test_connect [::1]:6502 1] Waiting for the server to receive it... 188s [test_connect [::1]:6502 1] Waiting for the server to send something 188s [test_connect [::1]:6502 1] Trying to receive the actual data 188s [test_connect [::1]:6502 1] Waiting for the server to close the connection 188s [[::1]:55130] Telling the main thread done 188s [test_connect [::1]:6502 1] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 1] Closing our writer socket, too 188s [test_connect [::1]:6502 1] Letting the main thread know we're done 188s [test_connect [::1]:6502 1] Done 188s [process_connections] Fetching task plain-1 188s [process_connections] Waiting for task plain-1 188s [process_connections] Done with task plain-1 188s [test_connect [::1]:6502 0] Opening an unencrypted connection 188s [test_connect [::1]:6502 5] Opening an unencrypted connection 188s [listener] A client connected from somewhere 188s [[::1]:55190] New connection 188s [[::1]:55190] Telling the main thread 188s [[::1]:55190] Let them tell us something... 188s [listener] A client connected from somewhere 188s [[::1]:55200] New connection 188s [[::1]:55200] Telling the main thread 188s [[::1]:55200] Let them tell us something... 188s [test_connect [::1]:6502 0] Connected to the server: [::1]:55190 188s [test_connect [::1]:6502 5] Connected to the server: [::1]:55200 188s [test_connect [::1]:6502 8] Sending something... 188s [test_connect [::1]:6502 8] Waiting for the main thread to figure it out 188s [test_connect [::1]:6502 2] Sending something... 188s [test_connect [::1]:6502 2] Waiting for the main thread to figure it out 188s [[::1]:55164] Telling the main thread about b'Hello 8\n' 188s [[::1]:55164] Writing something back... 188s [[::1]:55164] Telling the main thread about b'There!\n' 188s [[::1]:55164] Closing down the writer socket 188s [[::1]:55124] Telling the main thread about b'Hello 2\n' 188s [[::1]:55124] Writing something back... 188s [[::1]:55124] Telling the main thread about b'There!\n' 188s [[::1]:55124] Closing down the writer socket 188s [test_connect [::1]:6502 8] The listener acknowledged [::1]:55164 188s [test_connect [::1]:6502 8] Waiting for the server to receive it... 188s [test_connect [::1]:6502 8] Waiting for the server to send something 188s [test_connect [::1]:6502 8] Trying to receive the actual data 188s [test_connect [::1]:6502 8] Waiting for the server to close the connection 188s [test_connect [::1]:6502 2] The listener acknowledged [::1]:55124 188s [test_connect [::1]:6502 2] Waiting for the server to receive it... 188s [test_connect [::1]:6502 2] Waiting for the server to send something 188s [test_connect [::1]:6502 2] Trying to receive the actual data 188s [test_connect [::1]:6502 2] Waiting for the server to close the connection 188s [[::1]:55164] Telling the main thread done 188s [[::1]:55124] Telling the main thread done 188s [test_connect [::1]:6502 8] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 8] Closing our writer socket, too 188s [test_connect [::1]:6502 2] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 2] Closing our writer socket, too 188s [test_connect [::1]:6502 8] Letting the main thread know we're done 188s [test_connect [::1]:6502 8] Done 188s [test_connect [::1]:6502 2] Letting the main thread know we're done 188s [test_connect [::1]:6502 2] Done 188s [process_connections] Fetching task plain-8 188s [process_connections] Waiting for task plain-8 188s [process_connections] Done with task plain-8 188s [process_connections] Fetching task plain-2 188s [process_connections] Waiting for task plain-2 188s [process_connections] Done with task plain-2 188s [test_connect [::1]:6502 4] Sending something... 188s [test_connect [::1]:6502 4] Waiting for the main thread to figure it out 188s [[::1]:55144] Telling the main thread about b'Hello 4\n' 188s [[::1]:55144] Writing something back... 188s [[::1]:55144] Telling the main thread about b'There!\n' 188s [[::1]:55144] Closing down the writer socket 188s [test_connect [::1]:6502 4] The listener acknowledged [::1]:55144 188s [test_connect [::1]:6502 4] Waiting for the server to receive it... 188s [test_connect [::1]:6502 4] Waiting for the server to send something 188s [test_connect [::1]:6502 4] Trying to receive the actual data 188s [test_connect [::1]:6502 4] Waiting for the server to close the connection 188s [[::1]:55144] Telling the main thread done 188s [test_connect [::1]:6502 4] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 4] Closing our writer socket, too 188s [test_connect [::1]:6502 4] Letting the main thread know we're done 188s [test_connect [::1]:6502 4] Done 188s [process_connections] Fetching task plain-4 188s [process_connections] Waiting for task plain-4 188s [process_connections] Done with task plain-4 188s [test_connect [::1]:6502 7] Sending something... 188s [test_connect [::1]:6502 7] Waiting for the main thread to figure it out 188s [[::1]:55160] Telling the main thread about b'Hello 7\n' 188s [[::1]:55160] Writing something back... 188s [[::1]:55160] Telling the main thread about b'There!\n' 188s [[::1]:55160] Closing down the writer socket 188s [test_connect [::1]:6502 7] The listener acknowledged [::1]:55160 188s [test_connect [::1]:6502 7] Waiting for the server to receive it... 188s [test_connect [::1]:6502 7] Waiting for the server to send something 188s [test_connect [::1]:6502 7] Trying to receive the actual data 188s [test_connect [::1]:6502 7] Waiting for the server to close the connection 188s [[::1]:55160] Telling the main thread done 188s [test_connect [::1]:6502 7] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 7] Closing our writer socket, too 188s [test_connect [::1]:6502 7] Letting the main thread know we're done 188s [test_connect [::1]:6502 7] Done 188s [process_connections] Fetching task plain-7 188s [process_connections] Waiting for task plain-7 188s [process_connections] Done with task plain-7 188s [test_connect [::1]:6502 3] Sending something... 188s [test_connect [::1]:6502 3] Waiting for the main thread to figure it out 188s [test_connect [::1]:6502 6] Sending something... 188s [test_connect [::1]:6502 6] Waiting for the main thread to figure it out 188s [[::1]:55172] Telling the main thread about b'Hello 3\n' 188s [[::1]:55172] Writing something back... 188s [[::1]:55172] Telling the main thread about b'There!\n' 188s [[::1]:55172] Closing down the writer socket 188s [[::1]:55180] Telling the main thread about b'Hello 6\n' 188s [[::1]:55180] Writing something back... 188s [[::1]:55180] Telling the main thread about b'There!\n' 188s [[::1]:55180] Closing down the writer socket 188s [test_connect [::1]:6502 3] The listener acknowledged [::1]:55172 188s [test_connect [::1]:6502 3] Waiting for the server to receive it... 188s [test_connect [::1]:6502 3] Waiting for the server to send something 188s [test_connect [::1]:6502 3] Trying to receive the actual data 188s [test_connect [::1]:6502 3] Waiting for the server to close the connection 188s [test_connect [::1]:6502 6] The listener acknowledged [::1]:55180 188s [test_connect [::1]:6502 6] Waiting for the server to receive it... 188s [test_connect [::1]:6502 6] Waiting for the server to send something 188s [test_connect [::1]:6502 6] Trying to receive the actual data 188s [test_connect [::1]:6502 6] Waiting for the server to close the connection 188s [[::1]:55172] Telling the main thread done 188s [[::1]:55180] Telling the main thread done 188s [test_connect [::1]:6502 3] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 3] Closing our writer socket, too 188s [test_connect [::1]:6502 6] Waiting for an EOF on the reader socket 188s [test_connect [::1]:6502 6] Closing our writer socket, too 188s [test_connect [::1]:6502 3] Letting the main thread know we're done 188s [test_connect [::1]:6502 3] Done 188s [test_connect [::1]:6502 6] Letting the main thread know we're done 188s [test_connect [::1]:6502 6] Done 188s [process_connections] Fetching task plain-3 188s [process_connections] Waiting for task plain-3 188s [process_connections] Done with task plain-3 188s [process_connections] Fetching task plain-6 188s [process_connections] Waiting for task plain-6 188s [process_connections] Done with task plain-6 189s [test_connect [::1]:6502 0] Sending something... 189s [test_connect [::1]:6502 0] Waiting for the main thread to figure it out 189s [test_connect [::1]:6502 5] Sending something... 189s [test_connect [::1]:6502 5] Waiting for the main thread to figure it out 189s [[::1]:55190] Telling the main thread about b'Hello 0\n' 189s [[::1]:55190] Writing something back... 189s [[::1]:55190] Telling the main thread about b'There!\n' 189s [[::1]:55190] Closing down the writer socket 189s [[::1]:55200] Telling the main thread about b'Hello 5\n' 189s [[::1]:55200] Writing something back... 189s [[::1]:55200] Telling the main thread about b'There!\n' 189s [[::1]:55200] Closing down the writer socket 189s [test_connect [::1]:6502 0] The listener acknowledged [::1]:55190 189s [test_connect [::1]:6502 0] Waiting for the server to receive it... 189s [test_connect [::1]:6502 0] Waiting for the server to send something 189s [test_connect [::1]:6502 0] Trying to receive the actual data 189s [test_connect [::1]:6502 0] Waiting for the server to close the connection 189s [test_connect [::1]:6502 5] The listener acknowledged [::1]:55200 189s [test_connect [::1]:6502 5] Waiting for the server to receive it... 189s [test_connect [::1]:6502 5] Waiting for the server to send something 189s [test_connect [::1]:6502 5] Trying to receive the actual data 189s [test_connect [::1]:6502 5] Waiting for the server to close the connection 189s [[::1]:55190] Telling the main thread done 189s [[::1]:55200] Telling the main thread done 189s [test_connect [::1]:6502 0] Waiting for an EOF on the reader socket 189s [test_connect [::1]:6502 0] Closing our writer socket, too 189s [test_connect [::1]:6502 5] Waiting for an EOF on the reader socket 189s [test_connect [::1]:6502 5] Closing our writer socket, too 189s [test_connect [::1]:6502 0] Letting the main thread know we're done 189s [test_connect [::1]:6502 0] Done 189s [test_connect [::1]:6502 5] Letting the main thread know we're done 189s [test_connect [::1]:6502 5] Done 189s [process_connections] Fetching task plain-0 189s [process_connections] Waiting for task plain-0 189s [process_connections] Done with task plain-0 189s [process_connections] Fetching task plain-5 189s [process_connections] Waiting for task plain-5 189s [process_connections] Done with task plain-5 189s [main] Picking a listen address:port for stunnel 189s [find_listening_port] Looking for a port to listen on 189s [find_listening_port] Trying ::1 port 6503 189s [find_listening_port] got 6503, shutting down the listener 189s [main] Will put stunnel at ::1:6503 189s [main] Preparing the stunnel config file 189s [main] Using config file /tmp/struntime.rab511ek/stunnel.conf 189s [main] 189s [main] pid = /tmp/struntime.rab511ek/stunnel.pid 189s [main] foreground = yes 189s [main] 189s [main] cert = /tmp/autopkgtest.SckAzA/build.SMx/src/debian/tests/certs/certificate.pem 189s [main] key = /tmp/struntime.rab511ek/key.pem 189s [main] 189s [main] [test] 189s [main] accept = ::1:6503 189s [main] connect = ::1:6502 189s [main] End of the config file 189s [main] Launching the stunnel server 189s [main] - got pid 1288 189s [main] Launching the stunnel output pipe thread 189s [main] Waiting for stunnel to start up 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: stunnel 5.70 on s390x-ibm-linux-gnu platform' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: Compiled with OpenSSL 3.0.10 1 Aug 2023' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: Running with OpenSSL 3.0.13 30 Jan 2024' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: Reading configuration from file /tmp/struntime.rab511ek/stunnel.conf' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: UTF-8 byte order mark not detected' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: FIPS mode disabled' 189s [stunnel_output] Read '2024.03.21 15:27:21 LOG5[ui]: Configuration successful' 189s [stunnel_output] The main thread ought to know 189s [main] Testing the encrypted connections 189s [test_connect [::1]:6503 0] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 1] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 2] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 3] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 4] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 5] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 6] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 7] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 8] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 9] Trying port 6503 encrypted True 189s [test_connect [::1]:6503 7] Creating an SSL context 189s [test_connect [::1]:6503 7] - cert required 189s [test_connect [::1]:6503 7] - load_verify_locations() 189s [test_connect [::1]:6503 7] Opening an SSL connection 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[0]: Service [test] accepted connection from ::1:46566' 189s [test_connect [::1]:6503 7] Connected to the server: [::1]:46566 189s [listener] A client connected from somewhere 189s [[::1]:55216] New connection 189s [[::1]:55216] Telling the main thread 189s [[::1]:55216] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[0]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[0]: Service [test] connected remote server from ::1:55216' 189s [test_connect [::1]:6503 6] Creating an SSL context 189s [test_connect [::1]:6503 6] - cert required 189s [test_connect [::1]:6503 6] - load_verify_locations() 189s [test_connect [::1]:6503 6] Opening an SSL connection 189s [test_connect [::1]:6503 9] Creating an SSL context 189s [test_connect [::1]:6503 9] - cert required 189s [test_connect [::1]:6503 9] - load_verify_locations() 189s [test_connect [::1]:6503 9] Opening an SSL connection 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[1]: Service [test] accepted connection from ::1:46570' 189s [test_connect [::1]:6503 6] Connected to the server: [::1]:46570 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[2]: Service [test] accepted connection from ::1:46580' 189s [test_connect [::1]:6503 9] Connected to the server: [::1]:46580 189s [listener] A client connected from somewhere 189s [[::1]:55218] New connection 189s [[::1]:55218] Telling the main thread 189s [[::1]:55218] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[2]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[2]: Service [test] connected remote server from ::1:55218' 189s [listener] A client connected from somewhere 189s [[::1]:55222] New connection 189s [[::1]:55222] Telling the main thread 189s [[::1]:55222] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[1]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[1]: Service [test] connected remote server from ::1:55222' 189s [test_connect [::1]:6503 3] Creating an SSL context 189s [test_connect [::1]:6503 3] - cert required 189s [test_connect [::1]:6503 3] - load_verify_locations() 189s [test_connect [::1]:6503 3] Opening an SSL connection 189s [test_connect [::1]:6503 4] Creating an SSL context 189s [test_connect [::1]:6503 4] - cert required 189s [test_connect [::1]:6503 4] - load_verify_locations() 189s [test_connect [::1]:6503 4] Opening an SSL connection 189s [test_connect [::1]:6503 8] Creating an SSL context 189s [test_connect [::1]:6503 8] - cert required 189s [test_connect [::1]:6503 8] - load_verify_locations() 189s [test_connect [::1]:6503 8] Opening an SSL connection 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[3]: Service [test] accepted connection from ::1:46584' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[4]: Service [test] accepted connection from ::1:46590' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[5]: Service [test] accepted connection from ::1:46598' 189s [test_connect [::1]:6503 4] Connected to the server: [::1]:46590 189s [test_connect [::1]:6503 8] Connected to the server: [::1]:46598 189s [test_connect [::1]:6503 3] Connected to the server: [::1]:46584 189s [listener] A client connected from somewhere 189s [[::1]:55226] New connection 189s [[::1]:55226] Telling the main thread 189s [[::1]:55226] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[3]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[3]: Service [test] connected remote server from ::1:55226' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[4]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[4]: Service [test] connected remote server from ::1:55240' 189s [listener] A client connected from somewhere 189s [[::1]:55240] New connection 189s [[::1]:55240] Telling the main thread 189s [[::1]:55240] Let them tell us something... 189s [listener] A client connected from somewhere 189s [[::1]:55244] New connection 189s [[::1]:55244] Telling the main thread 189s [[::1]:55244] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[5]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[5]: Service [test] connected remote server from ::1:55244' 189s [test_connect [::1]:6503 0] Creating an SSL context 189s [test_connect [::1]:6503 0] - cert required 189s [test_connect [::1]:6503 0] - load_verify_locations() 189s [test_connect [::1]:6503 0] Opening an SSL connection 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[6]: Service [test] accepted connection from ::1:46612' 189s [test_connect [::1]:6503 0] Connected to the server: [::1]:46612 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[6]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[6]: Service [test] connected remote server from ::1:55250' 189s [listener] A client connected from somewhere 189s [[::1]:55250] New connection 189s [[::1]:55250] Telling the main thread 189s [[::1]:55250] Let them tell us something... 189s [test_connect [::1]:6503 2] Creating an SSL context 189s [test_connect [::1]:6503 2] - cert required 189s [test_connect [::1]:6503 2] - load_verify_locations() 189s [test_connect [::1]:6503 2] Opening an SSL connection 189s [test_connect [::1]:6503 2] Connected to the server: [::1]:46618 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[7]: Service [test] accepted connection from ::1:46618' 189s [listener] A client connected from somewhere 189s [[::1]:55258] New connection 189s [[::1]:55258] Telling the main thread 189s [[::1]:55258] Let them tell us something... 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[7]: s_connect: connected ::1:6502' 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[7]: Service [test] connected remote server from ::1:55258' 189s [test_connect [::1]:6503 9] Sending something... 189s [test_connect [::1]:6503 9] Waiting for the main thread to figure it out 189s [[::1]:55218] Telling the main thread about b'Hello 9\n' 189s [[::1]:55218] Writing something back... 189s [[::1]:55218] Telling the main thread about b'There!\n' 189s [[::1]:55218] Closing down the writer socket 189s [test_connect [::1]:6503 9] The listener acknowledged [::1]:55218 189s [test_connect [::1]:6503 9] Waiting for the server to receive it... 189s [test_connect [::1]:6503 9] Waiting for the server to send something 189s [test_connect [::1]:6503 9] Trying to receive the actual data 189s [test_connect [::1]:6503 9] Waiting for the server to close the connection 189s [[::1]:55218] Telling the main thread done 189s [test_connect [::1]:6503 9] Waiting for an EOF on the reader socket 189s [test_connect [::1]:6503 9] Closing our writer socket, too 189s [stunnel_output] Read '2024.03.21 15:27:23 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 189s [test_connect [::1]:6503 9] Letting the main thread know we're done 189s [test_connect [::1]:6503 9] Done 189s [process_connections] Fetching task encr-9 189s [process_connections] Waiting for task encr-9 189s [process_connections] Done with task encr-9 190s [test_connect [::1]:6503 1] Creating an SSL context 190s [test_connect [::1]:6503 1] - cert required 190s [test_connect [::1]:6503 1] - load_verify_locations() 190s [test_connect [::1]:6503 1] Opening an SSL connection 190s [test_connect [::1]:6503 5] Creating an SSL context 190s [test_connect [::1]:6503 5] - cert required 190s [test_connect [::1]:6503 5] - load_verify_locations() 190s [test_connect [::1]:6503 5] Opening an SSL connection 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[8]: Service [test] accepted connection from ::1:46628' 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[9]: Service [test] accepted connection from ::1:46632' 190s [test_connect [::1]:6503 1] Connected to the server: [::1]:46628 190s [test_connect [::1]:6503 5] Connected to the server: [::1]:46632 190s [listener] A client connected from somewhere 190s [[::1]:55262] New connection 190s [[::1]:55262] Telling the main thread 190s [[::1]:55262] Let them tell us something... 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[8]: s_connect: connected ::1:6502' 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[8]: Service [test] connected remote server from ::1:55262' 190s [listener] A client connected from somewhere 190s [[::1]:55266] New connection 190s [[::1]:55266] Telling the main thread 190s [[::1]:55266] Let them tell us something... 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[9]: s_connect: connected ::1:6502' 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[9]: Service [test] connected remote server from ::1:55266' 190s [test_connect [::1]:6503 8] Sending something... 190s [test_connect [::1]:6503 8] Waiting for the main thread to figure it out 190s [[::1]:55244] Telling the main thread about b'Hello 8\n' 190s [[::1]:55244] Writing something back... 190s [[::1]:55244] Telling the main thread about b'There!\n' 190s [[::1]:55244] Closing down the writer socket 190s [test_connect [::1]:6503 8] The listener acknowledged [::1]:55244 190s [test_connect [::1]:6503 8] Waiting for the server to receive it... 190s [test_connect [::1]:6503 8] Waiting for the server to send something 190s [test_connect [::1]:6503 8] Trying to receive the actual data 190s [test_connect [::1]:6503 8] Waiting for the server to close the connection 190s [[::1]:55244] Telling the main thread done 190s [test_connect [::1]:6503 8] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 8] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 8] Letting the main thread know we're done 190s [test_connect [::1]:6503 8] Done 190s [process_connections] Fetching task encr-8 190s [process_connections] Waiting for task encr-8 190s [process_connections] Done with task encr-8 190s [test_connect [::1]:6503 0] Sending something... 190s [test_connect [::1]:6503 0] Waiting for the main thread to figure it out 190s [[::1]:55250] Telling the main thread about b'Hello 0\n' 190s [[::1]:55250] Writing something back... 190s [[::1]:55250] Telling the main thread about b'There!\n' 190s [[::1]:55250] Closing down the writer socket 190s [test_connect [::1]:6503 0] The listener acknowledged [::1]:55250 190s [test_connect [::1]:6503 0] Waiting for the server to receive it... 190s [test_connect [::1]:6503 0] Waiting for the server to send something 190s [test_connect [::1]:6503 0] Trying to receive the actual data 190s [test_connect [::1]:6503 0] Waiting for the server to close the connection 190s [[::1]:55250] Telling the main thread done 190s [test_connect [::1]:6503 0] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 0] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 0] Letting the main thread know we're done 190s [test_connect [::1]:6503 0] Done 190s [process_connections] Fetching task encr-0 190s [process_connections] Waiting for task encr-0 190s [process_connections] Done with task encr-0 190s [test_connect [::1]:6503 2] Sending something... 190s [test_connect [::1]:6503 2] Waiting for the main thread to figure it out 190s [test_connect [::1]:6503 6] Sending something... 190s [test_connect [::1]:6503 6] Waiting for the main thread to figure it out 190s [[::1]:55258] Telling the main thread about b'Hello 2\n' 190s [[::1]:55258] Writing something back... 190s [[::1]:55258] Telling the main thread about b'There!\n' 190s [[::1]:55258] Closing down the writer socket 190s [[::1]:55222] Telling the main thread about b'Hello 6\n' 190s [[::1]:55222] Writing something back... 190s [[::1]:55222] Telling the main thread about b'There!\n' 190s [[::1]:55222] Closing down the writer socket 190s [test_connect [::1]:6503 2] The listener acknowledged [::1]:55258 190s [test_connect [::1]:6503 2] Waiting for the server to receive it... 190s [test_connect [::1]:6503 2] Waiting for the server to send something 190s [test_connect [::1]:6503 2] Trying to receive the actual data 190s [test_connect [::1]:6503 6] The listener acknowledged [::1]:55222 190s [test_connect [::1]:6503 6] Waiting for the server to receive it... 190s [test_connect [::1]:6503 6] Waiting for the server to send something 190s [test_connect [::1]:6503 6] Trying to receive the actual data 190s [[::1]:55258] Telling the main thread done 190s [[::1]:55222] Telling the main thread done 190s [test_connect [::1]:6503 6] Waiting for the server to close the connection 190s [test_connect [::1]:6503 6] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 6] Closing our writer socket, too 190s [test_connect [::1]:6503 2] Waiting for the server to close the connection 190s [test_connect [::1]:6503 2] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 2] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 6] Letting the main thread know we're done 190s [test_connect [::1]:6503 6] Done 190s [test_connect [::1]:6503 2] Letting the main thread know we're done 190s [test_connect [::1]:6503 2] Done 190s [process_connections] Fetching task encr-6 190s [process_connections] Waiting for task encr-6 190s [process_connections] Done with task encr-6 190s [process_connections] Fetching task encr-2 190s [process_connections] Waiting for task encr-2 190s [process_connections] Done with task encr-2 190s [test_connect [::1]:6503 7] Sending something... 190s [test_connect [::1]:6503 7] Waiting for the main thread to figure it out 190s [[::1]:55216] Telling the main thread about b'Hello 7\n' 190s [[::1]:55216] Writing something back... 190s [[::1]:55216] Telling the main thread about b'There!\n' 190s [[::1]:55216] Closing down the writer socket 190s [test_connect [::1]:6503 7] The listener acknowledged [::1]:55216 190s [test_connect [::1]:6503 7] Waiting for the server to receive it... 190s [test_connect [::1]:6503 7] Waiting for the server to send something 190s [test_connect [::1]:6503 7] Trying to receive the actual data 190s [test_connect [::1]:6503 7] Waiting for the server to close the connection 190s [[::1]:55216] Telling the main thread done 190s [test_connect [::1]:6503 7] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 7] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 7] Letting the main thread know we're done 190s [test_connect [::1]:6503 7] Done 190s [process_connections] Fetching task encr-7 190s [process_connections] Waiting for task encr-7 190s [process_connections] Done with task encr-7 190s [test_connect [::1]:6503 4] Sending something... 190s [test_connect [::1]:6503 4] Waiting for the main thread to figure it out 190s [[::1]:55240] Telling the main thread about b'Hello 4\n' 190s [[::1]:55240] Writing something back... 190s [[::1]:55240] Telling the main thread about b'There!\n' 190s [[::1]:55240] Closing down the writer socket 190s [test_connect [::1]:6503 4] The listener acknowledged [::1]:55240 190s [test_connect [::1]:6503 4] Waiting for the server to receive it... 190s [test_connect [::1]:6503 4] Waiting for the server to send something 190s [test_connect [::1]:6503 4] Trying to receive the actual data 190s [test_connect [::1]:6503 4] Waiting for the server to close the connection 190s [[::1]:55240] Telling the main thread done 190s [test_connect [::1]:6503 4] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 4] Closing our writer socket, too 190s [test_connect [::1]:6503 4] Letting the main thread know we're done 190s [test_connect [::1]:6503 4] Done 190s [process_connections] Fetching task encr-4 190s [process_connections] Waiting for task encr-4 190s [process_connections] Done with task encr-4 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 3] Sending something... 190s [test_connect [::1]:6503 3] Waiting for the main thread to figure it out 190s [[::1]:55226] Telling the main thread about b'Hello 3\n' 190s [[::1]:55226] Writing something back... 190s [[::1]:55226] Telling the main thread about b'There!\n' 190s [[::1]:55226] Closing down the writer socket 190s [test_connect [::1]:6503 3] The listener acknowledged [::1]:55226 190s [test_connect [::1]:6503 3] Waiting for the server to receive it... 190s [test_connect [::1]:6503 3] Waiting for the server to send something 190s [test_connect [::1]:6503 3] Trying to receive the actual data 190s [test_connect [::1]:6503 3] Waiting for the server to close the connection 190s [[::1]:55226] Telling the main thread done 190s [test_connect [::1]:6503 3] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 3] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 3] Letting the main thread know we're done 190s [test_connect [::1]:6503 3] Done 190s [process_connections] Fetching task encr-3 190s [process_connections] Waiting for task encr-3 190s [process_connections] Done with task encr-3 190s [test_connect [::1]:6503 1] Sending something... 190s [test_connect [::1]:6503 1] Waiting for the main thread to figure it out 190s [[::1]:55262] Telling the main thread about b'Hello 1\n' 190s [[::1]:55262] Writing something back... 190s [[::1]:55262] Telling the main thread about b'There!\n' 190s [[::1]:55262] Closing down the writer socket 190s [test_connect [::1]:6503 1] The listener acknowledged [::1]:55262 190s [test_connect [::1]:6503 1] Waiting for the server to receive it... 190s [test_connect [::1]:6503 1] Waiting for the server to send something 190s [test_connect [::1]:6503 1] Trying to receive the actual data 190s [test_connect [::1]:6503 1] Waiting for the server to close the connection 190s [[::1]:55262] Telling the main thread done 190s [test_connect [::1]:6503 1] Waiting for an EOF on the reader socket 190s [test_connect [::1]:6503 1] Closing our writer socket, too 190s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 190s [test_connect [::1]:6503 1] Letting the main thread know we're done 190s [test_connect [::1]:6503 1] Done 190s [process_connections] Fetching task encr-1 190s [process_connections] Waiting for task encr-1 190s [process_connections] Done with task encr-1 191s [test_connect [::1]:6503 5] Sending something... 191s [test_connect [::1]:6503 5] Waiting for the main thread to figure it out 191s [[::1]:55266] Telling the main thread about b'Hello 5\n' 191s [[::1]:55266] Writing something back... 191s [[::1]:55266] Telling the main thread about b'There!\n' 191s [[::1]:55266] Closing down the writer socket 191s [test_connect [::1]:6503 5] The listener acknowledged [::1]:55266 191s [test_connect [::1]:6503 5] Waiting for the server to receive it... 191s [test_connect [::1]:6503 5] Waiting for the server to send something 191s [test_connect [::1]:6503 5] Trying to receive the actual data 191s [test_connect [::1]:6503 5] Waiting for the server to close the connection 191s [[::1]:55266] Telling the main thread done 191s [test_connect [::1]:6503 5] Waiting for an EOF on the reader socket 191s [test_connect [::1]:6503 5] Closing our writer socket, too 191s [stunnel_output] Read '2024.03.21 15:27:24 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 191s [test_connect [::1]:6503 5] Letting the main thread know we're done 191s [test_connect [::1]:6503 5] Done 191s [process_connections] Fetching task encr-5 191s [process_connections] Waiting for task encr-5 191s [process_connections] Done with task encr-5 191s [main] Everything seems to be all right! 191s About to cancel 2 remaining task(s) 191s - listen 191s - output 191s Waiting for the tasks to hopefully finish 191s About to kill and wait for 1 child process(es) 191s - pid 1288 191s Waiting for the processes to exit... 191s Got processes' exit status: [-9] 191s autopkgtest [15:27:25]: test debian-python: -----------------------] 193s debian-python PASS 193s autopkgtest [15:27:27]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 193s autopkgtest [15:27:27]: test upstream: preparing testbed 320s autopkgtest [15:29:33]: testbed dpkg architecture: s390x 320s autopkgtest [15:29:34]: testbed apt version: 2.7.12 320s autopkgtest [15:29:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 320s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 321s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 321s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3775 kB] 321s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [495 kB] 321s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 321s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [662 kB] 321s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 321s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 321s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 321s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3985 kB] 321s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 321s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 321s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 324s Fetched 9151 kB in 2s (3844 kB/s) 324s Reading package lists... 326s Reading package lists... 326s Building dependency tree... 326s Reading state information... 326s Calculating upgrade... 327s The following packages will be REMOVED: 327s libssl3 327s The following NEW packages will be installed: 327s libssl3t64 327s The following packages will be upgraded: 327s debianutils openssl 327s 2 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 327s Need to get 2775 kB of archives. 327s After this operation, 240 kB of additional disk space will be used. 327s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 327s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x openssl s390x 3.0.13-0ubuntu2 [1010 kB] 327s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libssl3t64 s390x 3.0.13-0ubuntu2 [1675 kB] 327s Fetched 2775 kB in 1s (4516 kB/s) 328s (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.) 328s Preparing to unpack .../debianutils_5.17_s390x.deb ... 328s Unpacking debianutils (5.17) over (5.16) ... 328s Setting up debianutils (5.17) ... 328s (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.) 328s Preparing to unpack .../openssl_3.0.13-0ubuntu2_s390x.deb ... 328s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 328s dpkg: libssl3:s390x: dependency problems, but removing anyway as you requested: 328s wget depends on libssl3 (>= 3.0.0). 328s tnftp depends on libssl3 (>= 3.0.0). 328s tcpdump depends on libssl3 (>= 3.0.0). 328s systemd-resolved depends on libssl3 (>= 3.0.0). 328s systemd depends on libssl3 (>= 3.0.0). 328s sudo depends on libssl3 (>= 3.0.0). 328s s390-tools depends on libssl3 (>= 3.0.0). 328s rsync depends on libssl3 (>= 3.0.0). 328s python3-cryptography depends on libssl3 (>= 3.0.0). 328s openssh-server depends on libssl3 (>= 3.0.10). 328s openssh-client depends on libssl3 (>= 3.0.10). 328s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 328s libsystemd-shared:s390x depends on libssl3 (>= 3.0.0). 328s libssh-4:s390x depends on libssl3 (>= 3.0.0). 328s libsasl2-modules:s390x depends on libssl3 (>= 3.0.0). 328s libsasl2-2:s390x depends on libssl3 (>= 3.0.0). 328s libpython3.12-minimal:s390x depends on libssl3 (>= 3.0.0). 328s libpython3.11-minimal:s390x depends on libssl3 (>= 3.0.0). 328s libnvme1 depends on libssl3 (>= 3.0.0). 328s libkrb5-3:s390x depends on libssl3 (>= 3.0.0). 328s libkmod2:s390x depends on libssl3 (>= 3.0.0). 328s libfido2-1:s390x depends on libssl3 (>= 3.0.0). 328s libcurl4:s390x depends on libssl3 (>= 3.0.0). 328s libcryptsetup12:s390x depends on libssl3 (>= 3.0.0). 328s kmod depends on libssl3 (>= 3.0.0). 328s dhcpcd-base depends on libssl3 (>= 3.0.0). 328s bind9-libs:s390x depends on libssl3 (>= 3.0.0). 328s 328s (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.) 328s Removing libssl3:s390x (3.0.10-1ubuntu4) ... 328s Selecting previously unselected package libssl3t64:s390x. 328s (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 ... 52160 files and directories currently installed.) 328s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_s390x.deb ... 328s Unpacking libssl3t64:s390x (3.0.13-0ubuntu2) ... 328s Setting up libssl3t64:s390x (3.0.13-0ubuntu2) ... 328s Setting up openssl (3.0.13-0ubuntu2) ... 328s Processing triggers for man-db (2.12.0-3) ... 329s Processing triggers for libc-bin (2.39-0ubuntu2) ... 329s Reading package lists... 329s Building dependency tree... 329s Reading state information... 330s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 330s Unknown architecture, assuming PC-style ttyS0 330s sh: Attempting to set up Debian/Ubuntu apt sources automatically 330s sh: Distribution appears to be Ubuntu 331s Reading package lists... 331s Building dependency tree... 331s Reading state information... 331s eatmydata is already the newest version (131-1). 331s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 331s Reading package lists... 331s Building dependency tree... 331s Reading state information... 331s dbus is already the newest version (1.14.10-4ubuntu1). 331s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 331s Reading package lists... 332s Building dependency tree... 332s Reading state information... 332s rng-tools-debian is already the newest version (2.4). 332s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 332s Reading package lists... 332s Building dependency tree... 332s Reading state information... 332s The following packages will be REMOVED: 332s cloud-init* python3-configobj* python3-debconf* 332s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 332s After this operation, 3252 kB disk space will be freed. 332s (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 ... 52173 files and directories currently installed.) 332s Removing cloud-init (24.1.1-0ubuntu1) ... 333s Removing python3-configobj (5.0.8-3) ... 333s Removing python3-debconf (1.5.86) ... 333s Processing triggers for man-db (2.12.0-3) ... 333s (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 ... 51784 files and directories currently installed.) 333s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 334s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 334s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 334s invoke-rc.d: policy-rc.d denied execution of try-restart. 334s Reading package lists... 334s Building dependency tree... 334s Reading state information... 334s linux-generic is already the newest version (6.8.0-11.11+1). 334s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 335s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 335s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 335s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 337s Reading package lists... 337s Reading package lists... 337s Building dependency tree... 337s Reading state information... 337s Calculating upgrade... 337s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 337s Reading package lists... 337s Building dependency tree... 337s Reading state information... 338s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 338s autopkgtest [15:29:52]: rebooting testbed after setup commands that affected boot 368s Reading package lists... 368s Building dependency tree... 368s Reading state information... 368s Starting pkgProblemResolver with broken count: 0 368s Starting 2 pkgProblemResolver with broken count: 0 368s Done 369s The following additional packages will be installed: 369s net-tools stunnel4 369s Suggested packages: 369s logcheck-database 369s The following NEW packages will be installed: 369s autopkgtest-satdep net-tools stunnel4 369s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 369s Need to get 420 kB/421 kB of archives. 369s After this operation, 1347 kB of additional disk space will be used. 369s Get:1 /tmp/autopkgtest.SckAzA/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B] 369s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x stunnel4 s390x 3:5.70-2 [219 kB] 369s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x net-tools s390x 2.10-0.1ubuntu3 [201 kB] 370s Fetched 420 kB in 0s (858 kB/s) 370s Selecting previously unselected package stunnel4. 370s (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 ... 51729 files and directories currently installed.) 370s Preparing to unpack .../stunnel4_3%3a5.70-2_s390x.deb ... 370s Unpacking stunnel4 (3:5.70-2) ... 370s Selecting previously unselected package net-tools. 370s Preparing to unpack .../net-tools_2.10-0.1ubuntu3_s390x.deb ... 370s Unpacking net-tools (2.10-0.1ubuntu3) ... 370s Selecting previously unselected package autopkgtest-satdep. 370s Preparing to unpack .../2-autopkgtest-satdep.deb ... 370s Unpacking autopkgtest-satdep (0) ... 370s Setting up stunnel4 (3:5.70-2) ... 370s Creating group 'stunnel4' with GID 988. 370s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 370s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 370s Setting up net-tools (2.10-0.1ubuntu3) ... 370s Setting up autopkgtest-satdep (0) ... 370s Processing triggers for man-db (2.12.0-3) ... 373s (Reading database ... 51822 files and directories currently installed.) 373s Removing autopkgtest-satdep (0) ... 375s autopkgtest [15:30:29]: test upstream: debian/tests/upstream 375s autopkgtest [15:30:29]: test upstream: [----------------------- 378s keys & certificates successfully generated 378s ./maketestcert.sh finished 378s 011. Test "cert" option 378s ...................................................................... succeeded 378s 012. Test no "cert" option 378s ...................................................................... succeeded 378s 021. Test "requireCert" option 378s ...................................................................... succeeded 378s 022. Failure test "requireCert" option 379s ...................................................................... succeeded 379s 031. Test "verifyPeer" option 379s ...................................................................... succeeded 379s 032. Failure test "verifyPeer" option 379s ...................................................................... succeeded 379s 041. Test "verifyChain" option 379s ...................................................................... succeeded 379s 042. Failure test "verifyChain" option 379s ...................................................................... succeeded 379s 051. CRL file with valid certificate 379s ...................................................................... succeeded 379s 052. CRL file with revoked certificate 379s ...................................................................... succeeded 379s 061. Test "PSKsecrets" option 379s ...................................................................... succeeded 379s 062. Failure test "PSKsecrets" option 379s ...................................................................... succeeded 379s 071. Test PKCS#12 certificate 379s ...................................................................... succeeded 379s 081. Test IPv6 support 379s ...................................................................... succeeded 379s 091. Binding server 379s ...................................................................... succeeded 379s 101. Test FIPS mode 379s ...................................................................... skipped 379s 111. Failure FIPS mode with unavailable cipher 379s ...................................................................... skipped 379s 112. Failure FIPS mode with unavailable ciphersuite 379s ...................................................................... skipped 379s 113. Failure FIPS mode with unavailable elliptic curve 379s ...................................................................... skipped 379s 121. Test "sni" option 379s ...................................................................... succeeded 379s 122. Failure test "sni" option 379s ...................................................................... succeeded 379s 131. Resume session with PSK authentication 379s ...................................................................... succeeded 379s 132. Cache session resumption with TLSv1.2 379s ...................................................................... succeeded 379s 133. Test enabled "sessionResume" option 379s ...................................................................... succeeded 379s 134. Test disabled "sessionResume" option 379s ...................................................................... succeeded 379s 141. Stateless session ticket resumption with TLSv1.2 380s ...................................................................... succeeded 380s 142. Stateless session ticket resumption with TLSv1.3 380s ...................................................................... succeeded 380s 151. Session resumption with secret keys 380s ...................................................................... succeeded 380s 161. Redirect connection (wrong peer certificate) 380s ...................................................................... succeeded 380s 162. Redirect connection (no peer certificate) 380s ...................................................................... succeeded 380s 163. Not redirected connection (valid peer certificate) 380s ...................................................................... succeeded 380s 164. Redirect connection (wrong chain) 380s ...................................................................... succeeded 380s 165. Redirect connection (no chain) 380s ...................................................................... succeeded 380s 166. Not redirected connection (valid chain) 380s ...................................................................... succeeded 380s 171. Redirect proxy connection (wrong peer certificate) 380s ...................................................................... succeeded 380s 172. Redirect proxy connection (no peer certificate) 380s ...................................................................... succeeded 380s 173. Not redirected proxy connection (valid peer certificate) 380s ...................................................................... succeeded 380s 181. Session resumption and redirect (wrong certificate) 381s ...................................................................... succeeded 381s 182. Session resumption and redirect (no certificate) 381s ...................................................................... succeeded 381s 183. Session resumption and no redirect (valid certificate) 381s ...................................................................... succeeded 381s 191. Reload configuration 381s ...................................................................... succeeded 381s 201. Failover round robin (rr) strategy 381s ...................................................................... succeeded 381s 202. Failover priority (prio) strategy 381s ...................................................................... succeeded 381s 211. Included configuration files 381s ...................................................................... succeeded 381s 221. Client inetd mode 381s ...................................................................... succeeded 381s 231. Test exec+connect service 381s ...................................................................... succeeded 381s 241. Resume session with delay option 381s ...................................................................... succeeded 381s 242. Resume session with disable delay option 382s ...................................................................... succeeded 382s 251. Wrong client configuration 382s ...................................................................... succeeded 382s 261. Wrong server configuration 382s ...................................................................... succeeded 382s 382s Summary: 382s succeeded: 46 382s failed: 0 382s skipped: 4 382s 382s Testing results: /tmp/autopkgtest.SckAzA/build.SMx/src/tests/logs/results.log 382s autopkgtest [15:30:36]: test upstream: -----------------------] 382s upstream PASS 382s autopkgtest [15:30:36]: test upstream: - - - - - - - - - - results - - - - - - - - - - 383s autopkgtest [15:30:37]: @@@@@@@@@@@@@@@@@@@@ summary 383s debian-python PASS 383s upstream PASS 395s Creating nova instance adt-noble-s390x-stunnel4-20240321-152414-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)... 395s Creating nova instance adt-noble-s390x-stunnel4-20240321-152414-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...