0s autopkgtest [16:23:45]: starting date and time: 2024-03-18 16:23:45+0000 0s autopkgtest [16:23:45]: git checkout: b506e79c ssh-setup/nova: fix ARCH having two lines of data 0s autopkgtest [16:23:45]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.b3_wduns/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --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-arm64-9.secgroup --name adt-noble-arm64-stunnel4-20240318-155726-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-arm64-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/ 145s autopkgtest [16:26:10]: testbed dpkg architecture: arm64 145s autopkgtest [16:26:10]: testbed apt version: 2.7.12 145s autopkgtest [16:26:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 147s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 147s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [51.4 kB] 148s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 148s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3728 kB] 149s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [485 kB] 149s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [654 kB] 149s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 149s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.6 kB] 149s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 149s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4102 kB] 150s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 150s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [55.3 kB] 150s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 158s Fetched 9245 kB in 5s (1922 kB/s) 159s Reading package lists... 165s Reading package lists... 166s Building dependency tree... 166s Reading state information... 167s Calculating upgrade... 168s The following packages will be REMOVED: 168s libssl3 168s The following NEW packages will be installed: 168s libssl3t64 168s The following packages will be upgraded: 168s openssl 169s 1 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 169s Need to get 2777 kB of archives. 169s After this operation, 139 kB of additional disk space will be used. 169s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu2 [985 kB] 169s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu2 [1793 kB] 171s Fetched 2777 kB in 1s (3153 kB/s) 171s (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 ... 75850 files and directories currently installed.) 171s Preparing to unpack .../openssl_3.0.13-0ubuntu2_arm64.deb ... 171s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 171s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 171s wget depends on libssl3 (>= 3.0.0). 171s u-boot-tools depends on libssl3 (>= 3.0.0). 171s tnftp depends on libssl3 (>= 3.0.0). 171s tcpdump depends on libssl3 (>= 3.0.0). 171s systemd-resolved depends on libssl3 (>= 3.0.0). 171s systemd depends on libssl3 (>= 3.0.0). 171s sudo depends on libssl3 (>= 3.0.0). 171s sbsigntool depends on libssl3 (>= 3.0.0). 171s rsync depends on libssl3 (>= 3.0.0). 171s python3-cryptography depends on libssl3 (>= 3.0.0). 171s openssh-server depends on libssl3 (>= 3.0.10). 171s openssh-client depends on libssl3 (>= 3.0.10). 171s mtd-utils depends on libssl3 (>= 3.0.0). 171s mokutil depends on libssl3 (>= 3.0.0). 171s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 171s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 171s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 171s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 171s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 171s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 171s libpython3.11-minimal:arm64 depends on libssl3 (>= 3.0.0). 171s libnvme1 depends on libssl3 (>= 3.0.0). 171s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 171s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 171s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 171s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 171s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 171s kmod depends on libssl3 (>= 3.0.0). 171s dhcpcd-base depends on libssl3 (>= 3.0.0). 171s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 171s 172s (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 ... 75850 files and directories currently installed.) 172s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 172s Selecting previously unselected package libssl3t64:arm64. 172s (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 ... 75839 files and directories currently installed.) 172s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_arm64.deb ... 172s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu2) ... 172s Setting up libssl3t64:arm64 (3.0.13-0ubuntu2) ... 172s Setting up openssl (3.0.13-0ubuntu2) ... 172s Processing triggers for man-db (2.12.0-3) ... 173s Processing triggers for libc-bin (2.39-0ubuntu2) ... 174s Reading package lists... 175s Building dependency tree... 175s Reading state information... 177s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 177s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 177s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 178s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 178s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 183s Reading package lists... 183s Reading package lists... 184s Building dependency tree... 184s Reading state information... 186s Calculating upgrade... 187s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 187s Reading package lists... 187s Building dependency tree... 188s Reading state information... 189s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 193s autopkgtest [16:26:58]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 193s autopkgtest [16:26:58]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 196s Get:1 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (dsc) [2581 B] 196s Get:2 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (tar) [883 kB] 196s Get:3 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (asc) [963 B] 196s Get:4 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (diff) [53.2 kB] 197s gpgv: Signature made Sun Sep 10 18:42:44 2023 UTC 197s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 197s gpgv: issuer "roam@debian.org" 197s gpgv: Can't check signature: No public key 197s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.70-2.dsc: no acceptable signature found 197s autopkgtest [16:27:02]: testing package stunnel4 version 3:5.70-2 198s autopkgtest [16:27:03]: build not needed 198s autopkgtest [16:27:03]: test debian-python: preparing testbed 200s Reading package lists... 201s Building dependency tree... 201s Reading state information... 202s Starting pkgProblemResolver with broken count: 0 202s Starting 2 pkgProblemResolver with broken count: 0 202s Done 204s The following additional packages will be installed: 204s python3-all stunnel4 204s Suggested packages: 204s logcheck-database 204s The following NEW packages will be installed: 204s autopkgtest-satdep python3-all stunnel4 204s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 204s Need to get 216 kB/217 kB of archives. 204s After this operation, 657 kB of additional disk space will be used. 204s Get:1 /tmp/autopkgtest.I6GSqL/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [712 B] 205s Get:2 http://ftpmaster.internal/ubuntu noble/universe arm64 stunnel4 arm64 3:5.70-2 [215 kB] 205s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 python3-all arm64 3.12.1-0ubuntu2 [906 B] 206s Fetched 216 kB in 1s (396 kB/s) 206s Selecting previously unselected package stunnel4. 206s (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 ... 75852 files and directories currently installed.) 206s Preparing to unpack .../stunnel4_3%3a5.70-2_arm64.deb ... 206s Unpacking stunnel4 (3:5.70-2) ... 206s Selecting previously unselected package python3-all. 206s Preparing to unpack .../python3-all_3.12.1-0ubuntu2_arm64.deb ... 206s Unpacking python3-all (3.12.1-0ubuntu2) ... 206s Selecting previously unselected package autopkgtest-satdep. 206s Preparing to unpack .../1-autopkgtest-satdep.deb ... 206s Unpacking autopkgtest-satdep (0) ... 207s Setting up stunnel4 (3:5.70-2) ... 207s Creating group 'stunnel4' with GID 988. 207s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 208s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 210s Setting up python3-all (3.12.1-0ubuntu2) ... 210s Setting up autopkgtest-satdep (0) ... 210s Processing triggers for man-db (2.12.0-3) ... 218s (Reading database ... 75897 files and directories currently installed.) 218s Removing autopkgtest-satdep (0) ... 219s autopkgtest [16:27:24]: 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 219s autopkgtest [16:27:24]: test debian-python: [----------------------- 219s 219s 219s ====== python3.12 219s 220s Using /tmp/struntime.miohf1_3 as a temporary directory 220s Trying to obtain the version of /usr/bin/stunnel4 220s Started `/usr/bin/stunnel4 -version` as process 2264 220s Got stunnel version 5.70 220s [main] Starting to do things 220s [main] Awaiting the 'listener started' event 220s [find_listening_port] Looking for a port to listen on 220s [find_listening_port] Trying localhost port 6502 220s [listener] Telling the main thread about [::1]:6502 220s [listener] Awaiting client connections... 220s [main] Apparently we are listening on [::1]:6502 220s [main] Testing cleartext connections 220s [test_connect [::1]:6502 0] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 1] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 2] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 3] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 4] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 5] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 6] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 7] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 8] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 9] Trying port 6502 encrypted False 220s [test_connect [::1]:6502 0] Opening an unencrypted connection 220s [test_connect [::1]:6502 1] Opening an unencrypted connection 220s [listener] A client connected from somewhere 220s [[::1]:47788] New connection 220s [[::1]:47788] Telling the main thread 220s [[::1]:47788] Let them tell us something... 220s [listener] A client connected from somewhere 220s [[::1]:47804] New connection 220s [[::1]:47804] Telling the main thread 220s [[::1]:47804] Let them tell us something... 220s [test_connect [::1]:6502 0] Connected to the server: [::1]:47788 220s [test_connect [::1]:6502 1] Connected to the server: [::1]:47804 220s [test_connect [::1]:6502 2] Opening an unencrypted connection 220s [test_connect [::1]:6502 9] Opening an unencrypted connection 220s [listener] A client connected from somewhere 220s [[::1]:47816] New connection 220s [[::1]:47816] Telling the main thread 220s [[::1]:47816] Let them tell us something... 220s [listener] A client connected from somewhere 220s [[::1]:47826] New connection 220s [[::1]:47826] Telling the main thread 220s [[::1]:47826] Let them tell us something... 220s [test_connect [::1]:6502 2] Connected to the server: [::1]:47816 220s [test_connect [::1]:6502 9] Connected to the server: [::1]:47826 220s [test_connect [::1]:6502 6] Opening an unencrypted connection 220s [test_connect [::1]:6502 8] Opening an unencrypted connection 220s [listener] A client connected from somewhere 220s [[::1]:47832] New connection 220s [[::1]:47832] Telling the main thread 220s [[::1]:47832] Let them tell us something... 220s [listener] A client connected from somewhere 220s [[::1]:47842] New connection 220s [[::1]:47842] Telling the main thread 220s [[::1]:47842] Let them tell us something... 220s [test_connect [::1]:6502 6] Connected to the server: [::1]:47832 220s [test_connect [::1]:6502 8] Connected to the server: [::1]:47842 220s [test_connect [::1]:6502 3] Opening an unencrypted connection 220s [test_connect [::1]:6502 5] Opening an unencrypted connection 220s [test_connect [::1]:6502 7] Opening an unencrypted connection 220s [listener] A client connected from somewhere 220s [[::1]:47848] New connection 220s [[::1]:47848] Telling the main thread 220s [[::1]:47848] Let them tell us something... 220s [listener] A client connected from somewhere 220s [[::1]:47852] New connection 220s [[::1]:47852] Telling the main thread 220s [[::1]:47852] Let them tell us something... 220s [listener] A client connected from somewhere 220s [[::1]:47854] New connection 220s [[::1]:47854] Telling the main thread 220s [[::1]:47854] Let them tell us something... 220s [test_connect [::1]:6502 3] Connected to the server: [::1]:47848 220s [test_connect [::1]:6502 5] Connected to the server: [::1]:47852 220s [test_connect [::1]:6502 7] Connected to the server: [::1]:47854 220s [test_connect [::1]:6502 4] Opening an unencrypted connection 220s [listener] A client connected from somewhere 220s [[::1]:47856] New connection 220s [[::1]:47856] Telling the main thread 220s [[::1]:47856] Let them tell us something... 220s [test_connect [::1]:6502 4] Connected to the server: [::1]:47856 221s [test_connect [::1]:6502 0] Sending something... 221s [test_connect [::1]:6502 0] Waiting for the main thread to figure it out 221s [[::1]:47788] Telling the main thread about b'Hello 0\n' 221s [[::1]:47788] Writing something back... 221s [[::1]:47788] Telling the main thread about b'There!\n' 221s [[::1]:47788] Closing down the writer socket 221s [test_connect [::1]:6502 0] The listener acknowledged [::1]:47788 221s [test_connect [::1]:6502 0] Waiting for the server to receive it... 221s [test_connect [::1]:6502 0] Waiting for the server to send something 221s [test_connect [::1]:6502 0] Trying to receive the actual data 221s [test_connect [::1]:6502 0] Waiting for the server to close the connection 221s [[::1]:47788] Telling the main thread done 221s [test_connect [::1]:6502 0] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 0] Closing our writer socket, too 221s [test_connect [::1]:6502 0] Letting the main thread know we're done 221s [test_connect [::1]:6502 0] Done 221s [process_connections] Fetching task plain-0 221s [process_connections] Waiting for task plain-0 221s [process_connections] Done with task plain-0 221s [test_connect [::1]:6502 7] Sending something... 221s [test_connect [::1]:6502 7] Waiting for the main thread to figure it out 221s [[::1]:47854] Telling the main thread about b'Hello 7\n' 221s [[::1]:47854] Writing something back... 221s [[::1]:47854] Telling the main thread about b'There!\n' 221s [[::1]:47854] Closing down the writer socket 221s [test_connect [::1]:6502 7] The listener acknowledged [::1]:47854 221s [test_connect [::1]:6502 7] Waiting for the server to receive it... 221s [test_connect [::1]:6502 7] Waiting for the server to send something 221s [test_connect [::1]:6502 7] Trying to receive the actual data 221s [test_connect [::1]:6502 7] Waiting for the server to close the connection 221s [[::1]:47854] Telling the main thread done 221s [test_connect [::1]:6502 7] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 7] Closing our writer socket, too 221s [test_connect [::1]:6502 7] Letting the main thread know we're done 221s [test_connect [::1]:6502 7] Done 221s [process_connections] Fetching task plain-7 221s [process_connections] Waiting for task plain-7 221s [process_connections] Done with task plain-7 221s [test_connect [::1]:6502 1] Sending something... 221s [test_connect [::1]:6502 1] Waiting for the main thread to figure it out 221s [[::1]:47804] Telling the main thread about b'Hello 1\n' 221s [[::1]:47804] Writing something back... 221s [[::1]:47804] Telling the main thread about b'There!\n' 221s [[::1]:47804] Closing down the writer socket 221s [test_connect [::1]:6502 5] Sending something... 221s [test_connect [::1]:6502 5] Waiting for the main thread to figure it out 221s [test_connect [::1]:6502 1] The listener acknowledged [::1]:47804 221s [test_connect [::1]:6502 1] Waiting for the server to receive it... 221s [test_connect [::1]:6502 1] Waiting for the server to send something 221s [test_connect [::1]:6502 1] Trying to receive the actual data 221s [test_connect [::1]:6502 1] Waiting for the server to close the connection 221s [[::1]:47804] Telling the main thread done 221s [[::1]:47852] Telling the main thread about b'Hello 5\n' 221s [[::1]:47852] Writing something back... 221s [[::1]:47852] Telling the main thread about b'There!\n' 221s [[::1]:47852] Closing down the writer socket 221s [test_connect [::1]:6502 1] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 1] Closing our writer socket, too 221s [test_connect [::1]:6502 5] The listener acknowledged [::1]:47852 221s [test_connect [::1]:6502 5] Waiting for the server to receive it... 221s [test_connect [::1]:6502 5] Waiting for the server to send something 221s [test_connect [::1]:6502 5] Trying to receive the actual data 221s [test_connect [::1]:6502 5] Waiting for the server to close the connection 221s [[::1]:47852] Telling the main thread done 221s [test_connect [::1]:6502 1] Letting the main thread know we're done 221s [test_connect [::1]:6502 1] Done 221s [test_connect [::1]:6502 5] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 5] Closing our writer socket, too 221s [process_connections] Fetching task plain-1 221s [process_connections] Waiting for task plain-1 221s [process_connections] Done with task plain-1 221s [test_connect [::1]:6502 5] Letting the main thread know we're done 221s [test_connect [::1]:6502 5] Done 221s [process_connections] Fetching task plain-5 221s [process_connections] Waiting for task plain-5 221s [process_connections] Done with task plain-5 221s [test_connect [::1]:6502 2] Sending something... 221s [test_connect [::1]:6502 2] Waiting for the main thread to figure it out 221s [test_connect [::1]:6502 9] Sending something... 221s [test_connect [::1]:6502 9] Waiting for the main thread to figure it out 221s [[::1]:47816] Telling the main thread about b'Hello 2\n' 221s [[::1]:47816] Writing something back... 221s [[::1]:47816] Telling the main thread about b'There!\n' 221s [[::1]:47816] Closing down the writer socket 221s [[::1]:47826] Telling the main thread about b'Hello 9\n' 221s [[::1]:47826] Writing something back... 221s [[::1]:47826] Telling the main thread about b'There!\n' 221s [[::1]:47826] Closing down the writer socket 221s [test_connect [::1]:6502 2] The listener acknowledged [::1]:47816 221s [test_connect [::1]:6502 2] Waiting for the server to receive it... 221s [test_connect [::1]:6502 2] Waiting for the server to send something 221s [test_connect [::1]:6502 2] Trying to receive the actual data 221s [test_connect [::1]:6502 2] Waiting for the server to close the connection 221s [test_connect [::1]:6502 9] The listener acknowledged [::1]:47826 221s [test_connect [::1]:6502 9] Waiting for the server to receive it... 221s [test_connect [::1]:6502 9] Waiting for the server to send something 221s [test_connect [::1]:6502 9] Trying to receive the actual data 221s [test_connect [::1]:6502 9] Waiting for the server to close the connection 221s [[::1]:47816] Telling the main thread done 221s [[::1]:47826] Telling the main thread done 221s [test_connect [::1]:6502 2] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 2] Closing our writer socket, too 221s [test_connect [::1]:6502 9] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 9] Closing our writer socket, too 221s [test_connect [::1]:6502 2] Letting the main thread know we're done 221s [test_connect [::1]:6502 2] Done 221s [test_connect [::1]:6502 9] Letting the main thread know we're done 221s [test_connect [::1]:6502 9] Done 221s [process_connections] Fetching task plain-2 221s [process_connections] Waiting for task plain-2 221s [process_connections] Done with task plain-2 221s [process_connections] Fetching task plain-9 221s [process_connections] Waiting for task plain-9 221s [process_connections] Done with task plain-9 221s [test_connect [::1]:6502 8] Sending something... 221s [test_connect [::1]:6502 8] Waiting for the main thread to figure it out 221s [test_connect [::1]:6502 3] Sending something... 221s [test_connect [::1]:6502 3] Waiting for the main thread to figure it out 221s [[::1]:47842] Telling the main thread about b'Hello 8\n' 221s [[::1]:47842] Writing something back... 221s [[::1]:47842] Telling the main thread about b'There!\n' 221s [[::1]:47842] Closing down the writer socket 221s [[::1]:47848] Telling the main thread about b'Hello 3\n' 221s [[::1]:47848] Writing something back... 221s [[::1]:47848] Telling the main thread about b'There!\n' 221s [[::1]:47848] Closing down the writer socket 221s [test_connect [::1]:6502 8] The listener acknowledged [::1]:47842 221s [test_connect [::1]:6502 8] Waiting for the server to receive it... 221s [test_connect [::1]:6502 8] Waiting for the server to send something 221s [test_connect [::1]:6502 8] Trying to receive the actual data 221s [test_connect [::1]:6502 8] Waiting for the server to close the connection 221s [test_connect [::1]:6502 3] The listener acknowledged [::1]:47848 221s [test_connect [::1]:6502 3] Waiting for the server to receive it... 221s [test_connect [::1]:6502 3] Waiting for the server to send something 221s [test_connect [::1]:6502 3] Trying to receive the actual data 221s [test_connect [::1]:6502 3] Waiting for the server to close the connection 221s [[::1]:47842] Telling the main thread done 221s [[::1]:47848] Telling the main thread done 221s [test_connect [::1]:6502 8] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 8] Closing our writer socket, too 221s [test_connect [::1]:6502 3] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 3] Closing our writer socket, too 221s [test_connect [::1]:6502 8] Letting the main thread know we're done 221s [test_connect [::1]:6502 8] Done 221s [test_connect [::1]:6502 3] Letting the main thread know we're done 221s [test_connect [::1]:6502 3] Done 221s [process_connections] Fetching task plain-8 221s [process_connections] Waiting for task plain-8 221s [process_connections] Done with task plain-8 221s [process_connections] Fetching task plain-3 221s [process_connections] Waiting for task plain-3 221s [process_connections] Done with task plain-3 221s [test_connect [::1]:6502 6] Sending something... 221s [test_connect [::1]:6502 6] Waiting for the main thread to figure it out 221s [[::1]:47832] Telling the main thread about b'Hello 6\n' 221s [[::1]:47832] Writing something back... 221s [[::1]:47832] Telling the main thread about b'There!\n' 221s [[::1]:47832] Closing down the writer socket 221s [test_connect [::1]:6502 6] The listener acknowledged [::1]:47832 221s [test_connect [::1]:6502 6] Waiting for the server to receive it... 221s [test_connect [::1]:6502 6] Waiting for the server to send something 221s [test_connect [::1]:6502 6] Trying to receive the actual data 221s [test_connect [::1]:6502 6] Waiting for the server to close the connection 221s [[::1]:47832] Telling the main thread done 221s [test_connect [::1]:6502 6] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 6] Closing our writer socket, too 221s [test_connect [::1]:6502 6] Letting the main thread know we're done 221s [test_connect [::1]:6502 6] Done 221s [process_connections] Fetching task plain-6 221s [process_connections] Waiting for task plain-6 221s [process_connections] Done with task plain-6 221s [test_connect [::1]:6502 4] Sending something... 221s [test_connect [::1]:6502 4] Waiting for the main thread to figure it out 221s [[::1]:47856] Telling the main thread about b'Hello 4\n' 221s [[::1]:47856] Writing something back... 221s [[::1]:47856] Telling the main thread about b'There!\n' 221s [[::1]:47856] Closing down the writer socket 221s [test_connect [::1]:6502 4] The listener acknowledged [::1]:47856 221s [test_connect [::1]:6502 4] Waiting for the server to receive it... 221s [test_connect [::1]:6502 4] Waiting for the server to send something 221s [test_connect [::1]:6502 4] Trying to receive the actual data 221s [test_connect [::1]:6502 4] Waiting for the server to close the connection 221s [[::1]:47856] Telling the main thread done 221s [test_connect [::1]:6502 4] Waiting for an EOF on the reader socket 221s [test_connect [::1]:6502 4] Closing our writer socket, too 221s [test_connect [::1]:6502 4] Letting the main thread know we're done 221s [test_connect [::1]:6502 4] Done 221s [process_connections] Fetching task plain-4 221s [process_connections] Waiting for task plain-4 221s [process_connections] Done with task plain-4 221s [main] Picking a listen address:port for stunnel 221s [find_listening_port] Looking for a port to listen on 221s [find_listening_port] Trying ::1 port 6503 221s [find_listening_port] got 6503, shutting down the listener 221s [main] Will put stunnel at ::1:6503 221s [main] Preparing the stunnel config file 222s [main] Using config file /tmp/struntime.miohf1_3/stunnel.conf 222s [main] 222s [main] pid = /tmp/struntime.miohf1_3/stunnel.pid 222s [main] foreground = yes 222s [main] 222s [main] cert = /tmp/autopkgtest.I6GSqL/build.IND/src/debian/tests/certs/certificate.pem 222s [main] key = /tmp/struntime.miohf1_3/key.pem 222s [main] 222s [main] [test] 222s [main] accept = ::1:6503 222s [main] connect = ::1:6502 222s [main] End of the config file 222s [main] Launching the stunnel server 222s [main] - got pid 2268 222s [main] Launching the stunnel output pipe thread 222s [main] Waiting for stunnel to start up 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: stunnel 5.70 on aarch64-unknown-linux-gnu platform' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: Compiled with OpenSSL 3.0.10 1 Aug 2023' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: Running with OpenSSL 3.0.13 30 Jan 2024' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: Reading configuration from file /tmp/struntime.miohf1_3/stunnel.conf' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: UTF-8 byte order mark not detected' 222s [stunnel_output] Read '2024.03.18 16:27:26 LOG5[ui]: FIPS mode disabled' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[ui]: Configuration successful' 222s [stunnel_output] The main thread ought to know 222s [main] Testing the encrypted connections 222s [test_connect [::1]:6503 0] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 1] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 2] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 3] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 4] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 5] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 6] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 7] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 8] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 9] Trying port 6503 encrypted True 222s [test_connect [::1]:6503 4] Creating an SSL context 222s [test_connect [::1]:6503 4] - cert required 222s [test_connect [::1]:6503 4] - load_verify_locations() 222s [test_connect [::1]:6503 4] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[0]: Service [test] accepted connection from ::1:43990' 222s [test_connect [::1]:6503 4] Connected to the server: [::1]:43990 222s [listener] A client connected from somewhere 222s [[::1]:47868] New connection 222s [[::1]:47868] Telling the main thread 222s [[::1]:47868] Let them tell us something... 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[0]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[0]: Service [test] connected remote server from ::1:47868' 222s [test_connect [::1]:6503 2] Creating an SSL context 222s [test_connect [::1]:6503 2] - cert required 222s [test_connect [::1]:6503 2] - load_verify_locations() 222s [test_connect [::1]:6503 2] Opening an SSL connection 222s [test_connect [::1]:6503 6] Creating an SSL context 222s [test_connect [::1]:6503 6] - cert required 222s [test_connect [::1]:6503 6] - load_verify_locations() 222s [test_connect [::1]:6503 6] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[1]: Service [test] accepted connection from ::1:43996' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[2]: Service [test] accepted connection from ::1:44010' 222s [test_connect [::1]:6503 2] Connected to the server: [::1]:43996 222s [test_connect [::1]:6503 6] Connected to the server: [::1]:44010 222s [listener] A client connected from somewhere 222s [[::1]:47880] New connection 222s [[::1]:47880] Telling the main thread 222s [[::1]:47880] Let them tell us something... 222s [listener] A client connected from somewhere 222s [[::1]:47886] New connection 222s [[::1]:47886] Telling the main thread 222s [[::1]:47886] Let them tell us something... 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[1]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[1]: Service [test] connected remote server from ::1:47880' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[2]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[2]: Service [test] connected remote server from ::1:47886' 222s [test_connect [::1]:6503 8] Creating an SSL context 222s [test_connect [::1]:6503 8] - cert required 222s [test_connect [::1]:6503 8] - load_verify_locations() 222s [test_connect [::1]:6503 8] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[3]: Service [test] accepted connection from ::1:44022' 222s [test_connect [::1]:6503 8] Connected to the server: [::1]:44022 222s [listener] A client connected from somewhere 222s [[::1]:47894] New connection 222s [[::1]:47894] Telling the main thread 222s [[::1]:47894] Let them tell us something... 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[3]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[3]: Service [test] connected remote server from ::1:47894' 222s [test_connect [::1]:6503 1] Creating an SSL context 222s [test_connect [::1]:6503 1] - cert required 222s [test_connect [::1]:6503 1] - load_verify_locations() 222s [test_connect [::1]:6503 1] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[4]: Service [test] accepted connection from ::1:44030' 222s [test_connect [::1]:6503 1] Connected to the server: [::1]:44030 222s [listener] A client connected from somewhere 222s [[::1]:47910] New connection 222s [[::1]:47910] Telling the main thread 222s [[::1]:47910] Let them tell us something... 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[4]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[4]: Service [test] connected remote server from ::1:47910' 222s [test_connect [::1]:6503 3] Creating an SSL context 222s [test_connect [::1]:6503 3] - cert required 222s [test_connect [::1]:6503 3] - load_verify_locations() 222s [test_connect [::1]:6503 3] Opening an SSL connection 222s [test_connect [::1]:6503 7] Creating an SSL context 222s [test_connect [::1]:6503 7] - cert required 222s [test_connect [::1]:6503 7] - load_verify_locations() 222s [test_connect [::1]:6503 7] Opening an SSL connection 222s [test_connect [::1]:6503 9] Creating an SSL context 222s [test_connect [::1]:6503 9] - cert required 222s [test_connect [::1]:6503 9] - load_verify_locations() 222s [test_connect [::1]:6503 9] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[5]: Service [test] accepted connection from ::1:44046' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[6]: Service [test] accepted connection from ::1:44054' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[7]: Service [test] accepted connection from ::1:44068' 222s [test_connect [::1]:6503 3] Connected to the server: [::1]:44046 222s [test_connect [::1]:6503 7] Connected to the server: [::1]:44054 222s [test_connect [::1]:6503 9] Connected to the server: [::1]:44068 222s [test_connect [::1]:6503 6] Sending something... 222s [test_connect [::1]:6503 6] Waiting for the main thread to figure it out 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[5]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[5]: Service [test] connected remote server from ::1:47916' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[6]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[6]: Service [test] connected remote server from ::1:47920' 222s [listener] A client connected from somewhere 222s [[::1]:47916] New connection 222s [[::1]:47916] Telling the main thread 222s [[::1]:47916] Let them tell us something... 222s [listener] A client connected from somewhere 222s [[::1]:47920] New connection 222s [[::1]:47920] Telling the main thread 222s [[::1]:47920] Let them tell us something... 222s [listener] A client connected from somewhere 222s [[::1]:47924] New connection 222s [[::1]:47924] Telling the main thread 222s [[::1]:47924] Let them tell us something... 222s [[::1]:47886] Telling the main thread about b'Hello 6\n' 222s [[::1]:47886] Writing something back... 222s [[::1]:47886] Telling the main thread about b'There!\n' 222s [[::1]:47886] Closing down the writer socket 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[7]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[7]: Service [test] connected remote server from ::1:47924' 222s [test_connect [::1]:6503 6] The listener acknowledged [::1]:47886 222s [test_connect [::1]:6503 6] Waiting for the server to receive it... 222s [test_connect [::1]:6503 6] Waiting for the server to send something 222s [test_connect [::1]:6503 6] Trying to receive the actual data 222s [test_connect [::1]:6503 6] Waiting for the server to close the connection 222s [[::1]:47886] Telling the main thread done 222s [test_connect [::1]:6503 6] Waiting for an EOF on the reader socket 222s [test_connect [::1]:6503 6] Closing our writer socket, too 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 222s [test_connect [::1]:6503 6] Letting the main thread know we're done 222s [test_connect [::1]:6503 6] Done 222s [process_connections] Fetching task encr-6 222s [process_connections] Waiting for task encr-6 222s [process_connections] Done with task encr-6 222s [test_connect [::1]:6503 0] Creating an SSL context 222s [test_connect [::1]:6503 0] - cert required 222s [test_connect [::1]:6503 0] - load_verify_locations() 222s [test_connect [::1]:6503 0] Opening an SSL connection 222s [test_connect [::1]:6503 5] Creating an SSL context 222s [test_connect [::1]:6503 5] - cert required 222s [test_connect [::1]:6503 5] - load_verify_locations() 222s [test_connect [::1]:6503 5] Opening an SSL connection 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[8]: Service [test] accepted connection from ::1:44082' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[9]: Service [test] accepted connection from ::1:44088' 222s [test_connect [::1]:6503 0] Connected to the server: [::1]:44082 222s [test_connect [::1]:6503 5] Connected to the server: [::1]:44088 222s [test_connect [::1]:6503 4] Sending something... 222s [test_connect [::1]:6503 4] Waiting for the main thread to figure it out 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[8]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[8]: Service [test] connected remote server from ::1:47940' 222s [[::1]:47868] Telling the main thread about b'Hello 4\n' 222s [[::1]:47868] Writing something back... 222s [[::1]:47868] Telling the main thread about b'There!\n' 222s [[::1]:47868] Closing down the writer socket 222s [listener] A client connected from somewhere 222s [[::1]:47940] New connection 222s [[::1]:47940] Telling the main thread 222s [[::1]:47940] Let them tell us something... 222s [listener] A client connected from somewhere 222s [[::1]:47950] New connection 222s [[::1]:47950] Telling the main thread 222s [[::1]:47950] Let them tell us something... 222s [test_connect [::1]:6503 4] The listener acknowledged [::1]:47868 222s [test_connect [::1]:6503 4] Waiting for the server to receive it... 222s [test_connect [::1]:6503 4] Waiting for the server to send something 222s [test_connect [::1]:6503 4] Trying to receive the actual data 222s [[::1]:47868] Telling the main thread done 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[9]: s_connect: connected ::1:6502' 222s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[9]: Service [test] connected remote server from ::1:47950' 223s [test_connect [::1]:6503 4] Waiting for the server to close the connection 223s [test_connect [::1]:6503 4] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 4] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:27 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 4] Letting the main thread know we're done 223s [test_connect [::1]:6503 4] Done 223s [process_connections] Fetching task encr-4 223s [process_connections] Waiting for task encr-4 223s [process_connections] Done with task encr-4 223s [test_connect [::1]:6503 2] Sending something... 223s [test_connect [::1]:6503 2] Waiting for the main thread to figure it out 223s [[::1]:47880] Telling the main thread about b'Hello 2\n' 223s [[::1]:47880] Writing something back... 223s [[::1]:47880] Telling the main thread about b'There!\n' 223s [[::1]:47880] Closing down the writer socket 223s [test_connect [::1]:6503 2] The listener acknowledged [::1]:47880 223s [test_connect [::1]:6503 2] Waiting for the server to receive it... 223s [test_connect [::1]:6503 2] Waiting for the server to send something 223s [test_connect [::1]:6503 2] Trying to receive the actual data 223s [test_connect [::1]:6503 2] Waiting for the server to close the connection 223s [[::1]:47880] Telling the main thread done 223s [test_connect [::1]:6503 2] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 2] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 2] Letting the main thread know we're done 223s [test_connect [::1]:6503 2] Done 223s [process_connections] Fetching task encr-2 223s [process_connections] Waiting for task encr-2 223s [process_connections] Done with task encr-2 223s [test_connect [::1]:6503 5] Sending something... 223s [test_connect [::1]:6503 5] Waiting for the main thread to figure it out 223s [[::1]:47950] Telling the main thread about b'Hello 5\n' 223s [[::1]:47950] Writing something back... 223s [[::1]:47950] Telling the main thread about b'There!\n' 223s [[::1]:47950] Closing down the writer socket 223s [test_connect [::1]:6503 5] The listener acknowledged [::1]:47950 223s [test_connect [::1]:6503 5] Waiting for the server to receive it... 223s [test_connect [::1]:6503 5] Waiting for the server to send something 223s [test_connect [::1]:6503 5] Trying to receive the actual data 223s [test_connect [::1]:6503 5] Waiting for the server to close the connection 223s [[::1]:47950] Telling the main thread done 223s [test_connect [::1]:6503 5] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 5] Closing our writer socket, too 223s [test_connect [::1]:6503 3] Sending something... 223s [test_connect [::1]:6503 3] Waiting for the main thread to figure it out 223s [test_connect [::1]:6503 5] Letting the main thread know we're done 223s [test_connect [::1]:6503 5] Done 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [process_connections] Fetching task encr-5 223s [process_connections] Waiting for task encr-5 223s [process_connections] Done with task encr-5 223s [[::1]:47916] Telling the main thread about b'Hello 3\n' 223s [[::1]:47916] Writing something back... 223s [[::1]:47916] Telling the main thread about b'There!\n' 223s [[::1]:47916] Closing down the writer socket 223s [test_connect [::1]:6503 3] The listener acknowledged [::1]:47916 223s [test_connect [::1]:6503 3] Waiting for the server to receive it... 223s [test_connect [::1]:6503 3] Waiting for the server to send something 223s [test_connect [::1]:6503 3] Trying to receive the actual data 223s [test_connect [::1]:6503 3] Waiting for the server to close the connection 223s [[::1]:47916] Telling the main thread done 223s [test_connect [::1]:6503 3] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 3] Closing our writer socket, too 223s [test_connect [::1]:6503 3] Letting the main thread know we're done 223s [test_connect [::1]:6503 3] Done 223s [process_connections] Fetching task encr-3 223s [process_connections] Waiting for task encr-3 223s [process_connections] Done with task encr-3 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 9] Sending something... 223s [test_connect [::1]:6503 9] Waiting for the main thread to figure it out 223s [[::1]:47924] Telling the main thread about b'Hello 9\n' 223s [[::1]:47924] Writing something back... 223s [[::1]:47924] Telling the main thread about b'There!\n' 223s [[::1]:47924] Closing down the writer socket 223s [test_connect [::1]:6503 9] The listener acknowledged [::1]:47924 223s [test_connect [::1]:6503 9] Waiting for the server to receive it... 223s [test_connect [::1]:6503 9] Waiting for the server to send something 223s [test_connect [::1]:6503 9] Trying to receive the actual data 223s [[::1]:47924] Telling the main thread done 223s [test_connect [::1]:6503 9] Waiting for the server to close the connection 223s [test_connect [::1]:6503 9] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 9] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 9] Letting the main thread know we're done 223s [test_connect [::1]:6503 9] Done 223s [process_connections] Fetching task encr-9 223s [process_connections] Waiting for task encr-9 223s [process_connections] Done with task encr-9 223s [test_connect [::1]:6503 1] Sending something... 223s [test_connect [::1]:6503 1] Waiting for the main thread to figure it out 223s [[::1]:47910] Telling the main thread about b'Hello 1\n' 223s [[::1]:47910] Writing something back... 223s [[::1]:47910] Telling the main thread about b'There!\n' 223s [[::1]:47910] Closing down the writer socket 223s [test_connect [::1]:6503 1] The listener acknowledged [::1]:47910 223s [test_connect [::1]:6503 1] Waiting for the server to receive it... 223s [test_connect [::1]:6503 1] Waiting for the server to send something 223s [test_connect [::1]:6503 1] Trying to receive the actual data 223s [test_connect [::1]:6503 1] Waiting for the server to close the connection 223s [[::1]:47910] Telling the main thread done 223s [test_connect [::1]:6503 1] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 1] Closing our writer socket, too 223s [test_connect [::1]:6503 1] Letting the main thread know we're done 223s [test_connect [::1]:6503 1] Done 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [process_connections] Fetching task encr-1 223s [process_connections] Waiting for task encr-1 223s [process_connections] Done with task encr-1 223s [test_connect [::1]:6503 0] Sending something... 223s [test_connect [::1]:6503 0] Waiting for the main thread to figure it out 223s [[::1]:47940] Telling the main thread about b'Hello 0\n' 223s [[::1]:47940] Writing something back... 223s [[::1]:47940] Telling the main thread about b'There!\n' 223s [[::1]:47940] Closing down the writer socket 223s [test_connect [::1]:6503 0] The listener acknowledged [::1]:47940 223s [test_connect [::1]:6503 0] Waiting for the server to receive it... 223s [test_connect [::1]:6503 0] Waiting for the server to send something 223s [test_connect [::1]:6503 0] Trying to receive the actual data 223s [test_connect [::1]:6503 0] Waiting for the server to close the connection 223s [[::1]:47940] Telling the main thread done 223s [test_connect [::1]:6503 0] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 0] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 0] Letting the main thread know we're done 223s [test_connect [::1]:6503 0] Done 223s [process_connections] Fetching task encr-0 223s [process_connections] Waiting for task encr-0 223s [process_connections] Done with task encr-0 223s [test_connect [::1]:6503 8] Sending something... 223s [test_connect [::1]:6503 8] Waiting for the main thread to figure it out 223s [[::1]:47894] Telling the main thread about b'Hello 8\n' 223s [[::1]:47894] Writing something back... 223s [[::1]:47894] Telling the main thread about b'There!\n' 223s [[::1]:47894] Closing down the writer socket 223s [test_connect [::1]:6503 8] The listener acknowledged [::1]:47894 223s [test_connect [::1]:6503 8] Waiting for the server to receive it... 223s [test_connect [::1]:6503 8] Waiting for the server to send something 223s [test_connect [::1]:6503 8] Trying to receive the actual data 223s [test_connect [::1]:6503 8] Waiting for the server to close the connection 223s [[::1]:47894] Telling the main thread done 223s [test_connect [::1]:6503 8] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 8] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 8] Letting the main thread know we're done 223s [test_connect [::1]:6503 8] Done 223s [process_connections] Fetching task encr-8 223s [process_connections] Waiting for task encr-8 223s [process_connections] Done with task encr-8 223s [test_connect [::1]:6503 7] Sending something... 223s [test_connect [::1]:6503 7] Waiting for the main thread to figure it out 223s [[::1]:47920] Telling the main thread about b'Hello 7\n' 223s [[::1]:47920] Writing something back... 223s [[::1]:47920] Telling the main thread about b'There!\n' 223s [[::1]:47920] Closing down the writer socket 223s [test_connect [::1]:6503 7] The listener acknowledged [::1]:47920 223s [test_connect [::1]:6503 7] Waiting for the server to receive it... 223s [test_connect [::1]:6503 7] Waiting for the server to send something 223s [test_connect [::1]:6503 7] Trying to receive the actual data 223s [[::1]:47920] Telling the main thread done 223s [test_connect [::1]:6503 7] Waiting for the server to close the connection 223s [test_connect [::1]:6503 7] Waiting for an EOF on the reader socket 223s [test_connect [::1]:6503 7] Closing our writer socket, too 223s [stunnel_output] Read '2024.03.18 16:27:28 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 223s [test_connect [::1]:6503 7] Letting the main thread know we're done 223s [test_connect [::1]:6503 7] Done 223s [process_connections] Fetching task encr-7 223s [process_connections] Waiting for task encr-7 223s [process_connections] Done with task encr-7 223s [main] Everything seems to be all right! 223s About to cancel 2 remaining task(s) 223s - listen 223s - output 223s Waiting for the tasks to hopefully finish 223s About to kill and wait for 1 child process(es) 223s - pid 2268 223s Waiting for the processes to exit... 223s Got processes' exit status: [-9] 224s autopkgtest [16:27:29]: test debian-python: -----------------------] 225s debian-python PASS 225s autopkgtest [16:27:30]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 225s autopkgtest [16:27:30]: test upstream: preparing testbed 335s autopkgtest [16:29:20]: testbed dpkg architecture: arm64 335s autopkgtest [16:29:20]: testbed apt version: 2.7.12 335s autopkgtest [16:29:20]: @@@@@@@@@@@@@@@@@@@@ test bed setup 336s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 337s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [51.4 kB] 337s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [485 kB] 338s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 338s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3728 kB] 340s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [654 kB] 340s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 340s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.6 kB] 340s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 340s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4102 kB] 341s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 341s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [55.3 kB] 341s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 346s Fetched 9245 kB in 5s (1744 kB/s) 346s Reading package lists... 350s Reading package lists... 351s Building dependency tree... 351s Reading state information... 352s Calculating upgrade... 353s The following packages will be REMOVED: 353s libssl3 353s The following NEW packages will be installed: 353s libssl3t64 353s The following packages will be upgraded: 353s openssl 353s 1 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 353s Need to get 2777 kB of archives. 353s After this operation, 139 kB of additional disk space will be used. 353s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu2 [985 kB] 354s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu2 [1793 kB] 355s Fetched 2777 kB in 1s (2191 kB/s) 355s (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 ... 75850 files and directories currently installed.) 355s Preparing to unpack .../openssl_3.0.13-0ubuntu2_arm64.deb ... 355s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 356s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 356s wget depends on libssl3 (>= 3.0.0). 356s u-boot-tools depends on libssl3 (>= 3.0.0). 356s tnftp depends on libssl3 (>= 3.0.0). 356s tcpdump depends on libssl3 (>= 3.0.0). 356s systemd-resolved depends on libssl3 (>= 3.0.0). 356s systemd depends on libssl3 (>= 3.0.0). 356s sudo depends on libssl3 (>= 3.0.0). 356s sbsigntool depends on libssl3 (>= 3.0.0). 356s rsync depends on libssl3 (>= 3.0.0). 356s python3-cryptography depends on libssl3 (>= 3.0.0). 356s openssh-server depends on libssl3 (>= 3.0.10). 356s openssh-client depends on libssl3 (>= 3.0.10). 356s mtd-utils depends on libssl3 (>= 3.0.0). 356s mokutil depends on libssl3 (>= 3.0.0). 356s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 356s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 356s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 356s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 356s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 356s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 356s libpython3.11-minimal:arm64 depends on libssl3 (>= 3.0.0). 356s libnvme1 depends on libssl3 (>= 3.0.0). 356s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 356s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 356s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 356s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 356s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 356s kmod depends on libssl3 (>= 3.0.0). 356s dhcpcd-base depends on libssl3 (>= 3.0.0). 356s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 356s 356s (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 ... 75850 files and directories currently installed.) 356s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 356s Selecting previously unselected package libssl3t64:arm64. 356s (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 ... 75839 files and directories currently installed.) 356s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_arm64.deb ... 356s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu2) ... 356s Setting up libssl3t64:arm64 (3.0.13-0ubuntu2) ... 356s Setting up openssl (3.0.13-0ubuntu2) ... 356s Processing triggers for man-db (2.12.0-3) ... 357s Processing triggers for libc-bin (2.39-0ubuntu2) ... 357s Reading package lists... 358s Building dependency tree... 358s Reading state information... 359s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 360s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 360s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 360s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 360s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 364s Reading package lists... 364s Reading package lists... 364s Building dependency tree... 364s Reading state information... 365s Calculating upgrade... 366s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 366s Reading package lists... 367s Building dependency tree... 367s Reading state information... 368s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 373s Reading package lists... 373s Building dependency tree... 373s Reading state information... 374s Starting pkgProblemResolver with broken count: 0 374s Starting 2 pkgProblemResolver with broken count: 0 374s Done 376s The following additional packages will be installed: 376s net-tools stunnel4 376s Suggested packages: 376s logcheck-database 376s The following NEW packages will be installed: 376s autopkgtest-satdep net-tools stunnel4 376s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 376s Need to get 421 kB/422 kB of archives. 376s After this operation, 1884 kB of additional disk space will be used. 376s Get:1 /tmp/autopkgtest.I6GSqL/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [732 B] 376s Get:2 http://ftpmaster.internal/ubuntu noble/universe arm64 stunnel4 arm64 3:5.70-2 [215 kB] 376s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 net-tools arm64 2.10-0.1ubuntu3 [206 kB] 377s Fetched 421 kB in 1s (790 kB/s) 377s Selecting previously unselected package stunnel4. 377s (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 ... 75852 files and directories currently installed.) 377s Preparing to unpack .../stunnel4_3%3a5.70-2_arm64.deb ... 377s Unpacking stunnel4 (3:5.70-2) ... 377s Selecting previously unselected package net-tools. 377s Preparing to unpack .../net-tools_2.10-0.1ubuntu3_arm64.deb ... 377s Unpacking net-tools (2.10-0.1ubuntu3) ... 377s Selecting previously unselected package autopkgtest-satdep. 377s Preparing to unpack .../2-autopkgtest-satdep.deb ... 377s Unpacking autopkgtest-satdep (0) ... 377s Setting up stunnel4 (3:5.70-2) ... 377s Creating group 'stunnel4' with GID 988. 377s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 378s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 379s Setting up net-tools (2.10-0.1ubuntu3) ... 379s Setting up autopkgtest-satdep (0) ... 379s Processing triggers for man-db (2.12.0-3) ... 386s (Reading database ... 75945 files and directories currently installed.) 386s Removing autopkgtest-satdep (0) ... 389s autopkgtest [16:30:14]: test upstream: debian/tests/upstream 389s autopkgtest [16:30:14]: test upstream: [----------------------- 393s keys & certificates successfully generated 393s ./maketestcert.sh finished 393s 011. Test "cert" option 393s ...................................................................... succeeded 393s 012. Test no "cert" option 393s ...................................................................... succeeded 393s 021. Test "requireCert" option 393s ...................................................................... succeeded 393s 022. Failure test "requireCert" option 393s ...................................................................... succeeded 393s 031. Test "verifyPeer" option 393s ...................................................................... succeeded 393s 032. Failure test "verifyPeer" option 394s ...................................................................... succeeded 394s 041. Test "verifyChain" option 394s ...................................................................... succeeded 394s 042. Failure test "verifyChain" option 394s ...................................................................... succeeded 394s 051. CRL file with valid certificate 394s ...................................................................... succeeded 394s 052. CRL file with revoked certificate 394s ...................................................................... succeeded 394s 061. Test "PSKsecrets" option 394s ...................................................................... succeeded 394s 062. Failure test "PSKsecrets" option 394s ...................................................................... succeeded 394s 071. Test PKCS#12 certificate 394s ...................................................................... succeeded 394s 081. Test IPv6 support 394s ...................................................................... succeeded 394s 091. Binding server 394s ...................................................................... succeeded 394s 101. Test FIPS mode 394s ...................................................................... skipped 394s 111. Failure FIPS mode with unavailable cipher 394s ...................................................................... skipped 394s 112. Failure FIPS mode with unavailable ciphersuite 394s ...................................................................... skipped 394s 113. Failure FIPS mode with unavailable elliptic curve 394s ...................................................................... skipped 394s 121. Test "sni" option 394s ...................................................................... succeeded 394s 122. Failure test "sni" option 395s ...................................................................... succeeded 395s 131. Resume session with PSK authentication 395s ...................................................................... succeeded 395s 132. Cache session resumption with TLSv1.2 395s ...................................................................... succeeded 395s 133. Test enabled "sessionResume" option 395s ...................................................................... succeeded 395s 134. Test disabled "sessionResume" option 395s ...................................................................... succeeded 395s 141. Stateless session ticket resumption with TLSv1.2 396s ...................................................................... succeeded 396s 142. Stateless session ticket resumption with TLSv1.3 397s ...................................................................... succeeded 397s 151. Session resumption with secret keys 398s ...................................................................... succeeded 398s 161. Redirect connection (wrong peer certificate) 398s ...................................................................... succeeded 398s 162. Redirect connection (no peer certificate) 398s ...................................................................... succeeded 398s 163. Not redirected connection (valid peer certificate) 398s ...................................................................... succeeded 398s 164. Redirect connection (wrong chain) 398s ...................................................................... succeeded 398s 165. Redirect connection (no chain) 398s ...................................................................... succeeded 398s 166. Not redirected connection (valid chain) 398s ...................................................................... succeeded 398s 171. Redirect proxy connection (wrong peer certificate) 398s ...................................................................... succeeded 398s 172. Redirect proxy connection (no peer certificate) 398s ...................................................................... succeeded 398s 173. Not redirected proxy connection (valid peer certificate) 399s ...................................................................... succeeded 399s 181. Session resumption and redirect (wrong certificate) 399s ...................................................................... succeeded 399s 182. Session resumption and redirect (no certificate) 399s ...................................................................... succeeded 399s 183. Session resumption and no redirect (valid certificate) 399s ...................................................................... succeeded 399s 191. Reload configuration 399s ...................................................................... succeeded 399s 201. Failover round robin (rr) strategy 399s ...................................................................... succeeded 399s 202. Failover priority (prio) strategy 400s ...................................................................... succeeded 400s 211. Included configuration files 400s ...................................................................... succeeded 400s 221. Client inetd mode 400s ...................................................................... succeeded 400s 231. Test exec+connect service 400s ...................................................................... succeeded 400s 241. Resume session with delay option 401s ...................................................................... succeeded 401s 242. Resume session with disable delay option 402s ...................................................................... succeeded 402s 251. Wrong client configuration 402s ...................................................................... succeeded 402s 261. Wrong server configuration 402s ...................................................................... succeeded 402s 402s Summary: 402s succeeded: 46 402s failed: 0 402s skipped: 4 402s 402s Testing results: /tmp/autopkgtest.I6GSqL/build.IND/src/tests/logs/results.log 402s autopkgtest [16:30:27]: test upstream: -----------------------] 403s upstream PASS 403s autopkgtest [16:30:28]: test upstream: - - - - - - - - - - results - - - - - - - - - - 403s autopkgtest [16:30:28]: @@@@@@@@@@@@@@@@@@@@ summary 403s debian-python PASS 403s upstream PASS 419s Creating nova instance adt-noble-arm64-stunnel4-20240318-155726-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-arm64-server-20240318.img (UUID 7ae91b52-a2f6-4b2b-830b-40f0853811cb)... 419s Creating nova instance adt-noble-arm64-stunnel4-20240318-155726-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-arm64-server-20240318.img (UUID 7ae91b52-a2f6-4b2b-830b-40f0853811cb)...