0s autopkgtest [02:57:17]: starting date and time: 2024-03-19 02:57:17+0000 0s autopkgtest [02:57:17]: git checkout: b506e79c ssh-setup/nova: fix ARCH having two lines of data 0s autopkgtest [02:57:17]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.n08_uceq/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@bos01-ppc64el-11.secgroup --name adt-noble-ppc64el-stunnel4-20240319-025716-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-ppc64el-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://us.ports.ubuntu.com/ubuntu-ports/ 129s autopkgtest [02:59:26]: testbed dpkg architecture: ppc64el 129s autopkgtest [02:59:26]: testbed apt version: 2.7.12 129s autopkgtest [02:59:26]: @@@@@@@@@@@@@@@@@@@@ test bed setup 130s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 130s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3753 kB] 131s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.0 kB] 131s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [486 kB] 131s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 131s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [646 kB] 131s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 131s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 131s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 131s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4025 kB] 132s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 132s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [47.3 kB] 132s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 137s Fetched 9146 kB in 3s (3088 kB/s) 137s Reading package lists... 141s Reading package lists... 141s Building dependency tree... 141s Reading state information... 142s Calculating upgrade... 142s The following packages will be REMOVED: 142s libssl3 142s The following NEW packages will be installed: 142s libssl3t64 142s The following packages will be upgraded: 142s openssl 142s 1 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 142s Need to get 3151 kB of archives. 142s After this operation, 73.7 kB of additional disk space will be used. 142s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el openssl ppc64el 3.0.13-0ubuntu2 [1026 kB] 143s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libssl3t64 ppc64el 3.0.13-0ubuntu2 [2125 kB] 144s Fetched 3151 kB in 2s (2055 kB/s) 145s (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 ... 70095 files and directories currently installed.) 145s Preparing to unpack .../openssl_3.0.13-0ubuntu2_ppc64el.deb ... 145s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 145s dpkg: libssl3:ppc64el: dependency problems, but removing anyway as you requested: 145s wget depends on libssl3 (>= 3.0.0). 145s tnftp depends on libssl3 (>= 3.0.0). 145s tcpdump depends on libssl3 (>= 3.0.0). 145s systemd-resolved depends on libssl3 (>= 3.0.0). 145s systemd depends on libssl3 (>= 3.0.0). 145s sudo depends on libssl3 (>= 3.0.0). 145s rsync depends on libssl3 (>= 3.0.0). 145s python3-cryptography depends on libssl3 (>= 3.0.0). 145s openssh-server depends on libssl3 (>= 3.0.10). 145s openssh-client depends on libssl3 (>= 3.0.10). 145s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 145s libsystemd-shared:ppc64el depends on libssl3 (>= 3.0.0). 145s libssh-4:ppc64el depends on libssl3 (>= 3.0.0). 145s libsasl2-modules:ppc64el depends on libssl3 (>= 3.0.0). 145s libsasl2-2:ppc64el depends on libssl3 (>= 3.0.0). 145s libpython3.12-minimal:ppc64el depends on libssl3 (>= 3.0.0). 145s libpython3.11-minimal:ppc64el depends on libssl3 (>= 3.0.0). 145s libnvme1 depends on libssl3 (>= 3.0.0). 145s libkrb5-3:ppc64el depends on libssl3 (>= 3.0.0). 145s libkmod2:ppc64el depends on libssl3 (>= 3.0.0). 145s libfido2-1:ppc64el depends on libssl3 (>= 3.0.0). 145s libcurl4:ppc64el depends on libssl3 (>= 3.0.0). 145s libcryptsetup12:ppc64el depends on libssl3 (>= 3.0.0). 145s kmod depends on libssl3 (>= 3.0.0). 145s dhcpcd-base depends on libssl3 (>= 3.0.0). 145s bind9-libs:ppc64el depends on libssl3 (>= 3.0.0). 145s 145s (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 ... 70095 files and directories currently installed.) 145s Removing libssl3:ppc64el (3.0.10-1ubuntu4) ... 145s Selecting previously unselected package libssl3t64:ppc64el. 145s (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 ... 70084 files and directories currently installed.) 145s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_ppc64el.deb ... 145s Unpacking libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 145s Setting up libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 145s Setting up openssl (3.0.13-0ubuntu2) ... 145s Processing triggers for man-db (2.12.0-3) ... 147s Processing triggers for libc-bin (2.39-0ubuntu2) ... 147s Reading package lists... 148s Building dependency tree... 148s Reading state information... 149s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 149s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 149s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 149s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 149s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 151s Reading package lists... 151s Reading package lists... 152s Building dependency tree... 152s Reading state information... 153s Calculating upgrade... 153s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 153s Reading package lists... 153s Building dependency tree... 153s Reading state information... 154s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 158s autopkgtest [02:59:55]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 158s autopkgtest [02:59:55]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 161s Get:1 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (dsc) [2581 B] 161s Get:2 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (tar) [883 kB] 161s Get:3 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (asc) [963 B] 161s Get:4 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (diff) [53.2 kB] 161s gpgv: Signature made Sun Sep 10 18:42:44 2023 UTC 161s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 161s gpgv: issuer "roam@debian.org" 161s gpgv: Can't check signature: No public key 161s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.70-2.dsc: no acceptable signature found 161s autopkgtest [02:59:58]: testing package stunnel4 version 3:5.70-2 162s autopkgtest [02:59:59]: build not needed 166s autopkgtest [03:00:03]: test debian-python: preparing testbed 168s Reading package lists... 168s Building dependency tree... 168s Reading state information... 169s Starting pkgProblemResolver with broken count: 0 169s Starting 2 pkgProblemResolver with broken count: 0 169s Done 169s The following additional packages will be installed: 169s python3-all stunnel4 169s Suggested packages: 169s logcheck-database 169s The following NEW packages will be installed: 169s autopkgtest-satdep python3-all stunnel4 169s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 169s Need to get 234 kB/234 kB of archives. 169s After this operation, 723 kB of additional disk space will be used. 169s Get:1 /tmp/autopkgtest.hBducp/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 169s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el stunnel4 ppc64el 3:5.70-2 [233 kB] 170s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-all ppc64el 3.12.1-0ubuntu2 [904 B] 170s Fetched 234 kB in 1s (433 kB/s) 170s Selecting previously unselected package stunnel4. 170s (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 ... 70097 files and directories currently installed.) 170s Preparing to unpack .../stunnel4_3%3a5.70-2_ppc64el.deb ... 170s Unpacking stunnel4 (3:5.70-2) ... 170s Selecting previously unselected package python3-all. 170s Preparing to unpack .../python3-all_3.12.1-0ubuntu2_ppc64el.deb ... 170s Unpacking python3-all (3.12.1-0ubuntu2) ... 170s Selecting previously unselected package autopkgtest-satdep. 170s Preparing to unpack .../1-autopkgtest-satdep.deb ... 170s Unpacking autopkgtest-satdep (0) ... 170s Setting up stunnel4 (3:5.70-2) ... 170s Creating group 'stunnel4' with GID 988. 170s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 171s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 171s Setting up python3-all (3.12.1-0ubuntu2) ... 171s Setting up autopkgtest-satdep (0) ... 171s Processing triggers for man-db (2.12.0-3) ... 177s (Reading database ... 70142 files and directories currently installed.) 177s Removing autopkgtest-satdep (0) ... 178s autopkgtest [03:00:15]: 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 178s autopkgtest [03:00:15]: test debian-python: [----------------------- 179s 179s 179s ====== python3.12 179s 179s Using /tmp/struntime.7vymg6fg as a temporary directory 179s Trying to obtain the version of /usr/bin/stunnel4 179s Started `/usr/bin/stunnel4 -version` as process 2353 179s Got stunnel version 5.70 179s [main] Starting to do things 179s [main] Awaiting the 'listener started' event 179s [find_listening_port] Looking for a port to listen on 179s [find_listening_port] Trying localhost port 6502 179s [listener] Telling the main thread about [::1]:6502 179s [listener] Awaiting client connections... 179s [main] Apparently we are listening on [::1]:6502 179s [main] Testing cleartext connections 179s [test_connect [::1]:6502 0] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 1] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 2] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 3] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 4] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 5] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 6] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 7] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 8] Trying port 6502 encrypted False 179s [test_connect [::1]:6502 9] Trying port 6502 encrypted False 180s [test_connect [::1]:6502 2] Opening an unencrypted connection 180s [test_connect [::1]:6502 3] Opening an unencrypted connection 180s [listener] A client connected from somewhere 180s [[::1]:49910] New connection 180s [[::1]:49910] Telling the main thread 180s [[::1]:49910] Let them tell us something... 180s [listener] A client connected from somewhere 180s [[::1]:49914] New connection 180s [[::1]:49914] Telling the main thread 180s [[::1]:49914] Let them tell us something... 180s [test_connect [::1]:6502 2] Connected to the server: [::1]:49910 180s [test_connect [::1]:6502 3] Connected to the server: [::1]:49914 180s [test_connect [::1]:6502 0] Opening an unencrypted connection 180s [listener] A client connected from somewhere 180s [[::1]:49924] New connection 180s [[::1]:49924] Telling the main thread 180s [[::1]:49924] Let them tell us something... 180s [test_connect [::1]:6502 0] Connected to the server: [::1]:49924 180s [test_connect [::1]:6502 8] Opening an unencrypted connection 180s [listener] A client connected from somewhere 180s [[::1]:49940] New connection 180s [[::1]:49940] Telling the main thread 180s [[::1]:49940] Let them tell us something... 180s [test_connect [::1]:6502 8] Connected to the server: [::1]:49940 180s [test_connect [::1]:6502 1] Opening an unencrypted connection 180s [test_connect [::1]:6502 6] Opening an unencrypted connection 180s [listener] A client connected from somewhere 180s [[::1]:49954] New connection 180s [[::1]:49954] Telling the main thread 180s [[::1]:49954] Let them tell us something... 180s [listener] A client connected from somewhere 180s [[::1]:49956] New connection 180s [[::1]:49956] Telling the main thread 180s [[::1]:49956] Let them tell us something... 180s [test_connect [::1]:6502 1] Connected to the server: [::1]:49954 180s [test_connect [::1]:6502 6] Connected to the server: [::1]:49956 180s [test_connect [::1]:6502 5] Opening an unencrypted connection 180s [listener] A client connected from somewhere 180s [[::1]:49970] New connection 180s [[::1]:49970] Telling the main thread 180s [[::1]:49970] Let them tell us something... 180s [test_connect [::1]:6502 5] Connected to the server: [::1]:49970 180s [test_connect [::1]:6502 4] Opening an unencrypted connection 180s [test_connect [::1]:6502 7] Opening an unencrypted connection 180s [test_connect [::1]:6502 9] Opening an unencrypted connection 180s [test_connect [::1]:6502 0] Sending something... 180s [test_connect [::1]:6502 0] Waiting for the main thread to figure it out 180s [listener] A client connected from somewhere 180s [[::1]:49978] New connection 180s [[::1]:49978] Telling the main thread 180s [[::1]:49978] Let them tell us something... 180s [listener] A client connected from somewhere 180s [[::1]:49982] New connection 180s [[::1]:49982] Telling the main thread 180s [[::1]:49982] Let them tell us something... 180s [listener] A client connected from somewhere 180s [[::1]:49984] New connection 180s [[::1]:49984] Telling the main thread 180s [[::1]:49984] Let them tell us something... 180s [test_connect [::1]:6502 4] Connected to the server: [::1]:49978 180s [test_connect [::1]:6502 7] Connected to the server: [::1]:49982 180s [test_connect [::1]:6502 9] Connected to the server: [::1]:49984 180s [[::1]:49924] Telling the main thread about b'Hello 0\n' 180s [[::1]:49924] Writing something back... 180s [[::1]:49924] Telling the main thread about b'There!\n' 180s [[::1]:49924] Closing down the writer socket 180s [test_connect [::1]:6502 0] The listener acknowledged [::1]:49924 180s [test_connect [::1]:6502 0] Waiting for the server to receive it... 180s [test_connect [::1]:6502 0] Waiting for the server to send something 180s [test_connect [::1]:6502 0] Trying to receive the actual data 180s [test_connect [::1]:6502 0] Waiting for the server to close the connection 180s [[::1]:49924] Telling the main thread done 180s [test_connect [::1]:6502 3] Sending something... 180s [test_connect [::1]:6502 3] Waiting for the main thread to figure it out 180s [test_connect [::1]:6502 0] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 0] Closing our writer socket, too 180s [[::1]:49914] Telling the main thread about b'Hello 3\n' 180s [[::1]:49914] Writing something back... 180s [[::1]:49914] Telling the main thread about b'There!\n' 180s [[::1]:49914] Closing down the writer socket 180s [test_connect [::1]:6502 0] Letting the main thread know we're done 180s [test_connect [::1]:6502 0] Done 180s [test_connect [::1]:6502 3] The listener acknowledged [::1]:49914 180s [test_connect [::1]:6502 3] Waiting for the server to receive it... 180s [test_connect [::1]:6502 3] Waiting for the server to send something 180s [test_connect [::1]:6502 3] Trying to receive the actual data 180s [test_connect [::1]:6502 3] Waiting for the server to close the connection 180s [[::1]:49914] Telling the main thread done 180s [process_connections] Fetching task plain-0 180s [process_connections] Waiting for task plain-0 180s [process_connections] Done with task plain-0 180s [test_connect [::1]:6502 3] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 3] Closing our writer socket, too 180s [test_connect [::1]:6502 3] Letting the main thread know we're done 180s [test_connect [::1]:6502 3] Done 180s [process_connections] Fetching task plain-3 180s [process_connections] Waiting for task plain-3 180s [process_connections] Done with task plain-3 180s [test_connect [::1]:6502 1] Sending something... 180s [test_connect [::1]:6502 1] Waiting for the main thread to figure it out 180s [[::1]:49954] Telling the main thread about b'Hello 1\n' 180s [[::1]:49954] Writing something back... 180s [[::1]:49954] Telling the main thread about b'There!\n' 180s [[::1]:49954] Closing down the writer socket 180s [test_connect [::1]:6502 1] The listener acknowledged [::1]:49954 180s [test_connect [::1]:6502 1] Waiting for the server to receive it... 180s [test_connect [::1]:6502 1] Waiting for the server to send something 180s [test_connect [::1]:6502 1] Trying to receive the actual data 180s [test_connect [::1]:6502 1] Waiting for the server to close the connection 180s [[::1]:49954] Telling the main thread done 180s [test_connect [::1]:6502 1] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 1] Closing our writer socket, too 180s [test_connect [::1]:6502 1] Letting the main thread know we're done 180s [test_connect [::1]:6502 1] Done 180s [process_connections] Fetching task plain-1 180s [process_connections] Waiting for task plain-1 180s [process_connections] Done with task plain-1 180s [test_connect [::1]:6502 2] Sending something... 180s [test_connect [::1]:6502 2] Waiting for the main thread to figure it out 180s [[::1]:49910] Telling the main thread about b'Hello 2\n' 180s [[::1]:49910] Writing something back... 180s [[::1]:49910] Telling the main thread about b'There!\n' 180s [[::1]:49910] Closing down the writer socket 180s [test_connect [::1]:6502 2] The listener acknowledged [::1]:49910 180s [test_connect [::1]:6502 2] Waiting for the server to receive it... 180s [test_connect [::1]:6502 2] Waiting for the server to send something 180s [test_connect [::1]:6502 2] Trying to receive the actual data 180s [test_connect [::1]:6502 2] Waiting for the server to close the connection 180s [[::1]:49910] Telling the main thread done 180s [test_connect [::1]:6502 2] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 2] Closing our writer socket, too 180s [test_connect [::1]:6502 2] Letting the main thread know we're done 180s [test_connect [::1]:6502 2] Done 180s [process_connections] Fetching task plain-2 180s [process_connections] Waiting for task plain-2 180s [process_connections] Done with task plain-2 180s [test_connect [::1]:6502 5] Sending something... 180s [test_connect [::1]:6502 5] Waiting for the main thread to figure it out 180s [[::1]:49970] Telling the main thread about b'Hello 5\n' 180s [[::1]:49970] Writing something back... 180s [[::1]:49970] Telling the main thread about b'There!\n' 180s [[::1]:49970] Closing down the writer socket 180s [test_connect [::1]:6502 5] The listener acknowledged [::1]:49970 180s [test_connect [::1]:6502 5] Waiting for the server to receive it... 180s [test_connect [::1]:6502 5] Waiting for the server to send something 180s [test_connect [::1]:6502 5] Trying to receive the actual data 180s [test_connect [::1]:6502 5] Waiting for the server to close the connection 180s [[::1]:49970] Telling the main thread done 180s [test_connect [::1]:6502 5] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 5] Closing our writer socket, too 180s [test_connect [::1]:6502 5] Letting the main thread know we're done 180s [test_connect [::1]:6502 5] Done 180s [process_connections] Fetching task plain-5 180s [process_connections] Waiting for task plain-5 180s [process_connections] Done with task plain-5 180s [test_connect [::1]:6502 8] Sending something... 180s [test_connect [::1]:6502 8] Waiting for the main thread to figure it out 180s [[::1]:49940] Telling the main thread about b'Hello 8\n' 180s [[::1]:49940] Writing something back... 180s [[::1]:49940] Telling the main thread about b'There!\n' 180s [[::1]:49940] Closing down the writer socket 180s [test_connect [::1]:6502 8] The listener acknowledged [::1]:49940 180s [test_connect [::1]:6502 8] Waiting for the server to receive it... 180s [test_connect [::1]:6502 8] Waiting for the server to send something 180s [test_connect [::1]:6502 8] Trying to receive the actual data 180s [test_connect [::1]:6502 8] Waiting for the server to close the connection 180s [[::1]:49940] Telling the main thread done 180s [test_connect [::1]:6502 8] Waiting for an EOF on the reader socket 180s [test_connect [::1]:6502 8] Closing our writer socket, too 180s [test_connect [::1]:6502 8] Letting the main thread know we're done 180s [test_connect [::1]:6502 8] Done 180s [process_connections] Fetching task plain-8 180s [process_connections] Waiting for task plain-8 180s [process_connections] Done with task plain-8 181s [test_connect [::1]:6502 4] Sending something... 181s [test_connect [::1]:6502 4] Waiting for the main thread to figure it out 181s [[::1]:49978] Telling the main thread about b'Hello 4\n' 181s [[::1]:49978] Writing something back... 181s [[::1]:49978] Telling the main thread about b'There!\n' 181s [[::1]:49978] Closing down the writer socket 181s [test_connect [::1]:6502 4] The listener acknowledged [::1]:49978 181s [test_connect [::1]:6502 4] Waiting for the server to receive it... 181s [test_connect [::1]:6502 4] Waiting for the server to send something 181s [test_connect [::1]:6502 4] Trying to receive the actual data 181s [test_connect [::1]:6502 4] Waiting for the server to close the connection 181s [[::1]:49978] Telling the main thread done 181s [test_connect [::1]:6502 4] Waiting for an EOF on the reader socket 181s [test_connect [::1]:6502 4] Closing our writer socket, too 181s [test_connect [::1]:6502 4] Letting the main thread know we're done 181s [test_connect [::1]:6502 4] Done 181s [process_connections] Fetching task plain-4 181s [process_connections] Waiting for task plain-4 181s [process_connections] Done with task plain-4 181s [test_connect [::1]:6502 7] Sending something... 181s [test_connect [::1]:6502 7] Waiting for the main thread to figure it out 181s [test_connect [::1]:6502 6] Sending something... 181s [test_connect [::1]:6502 6] Waiting for the main thread to figure it out 181s [[::1]:49982] Telling the main thread about b'Hello 7\n' 181s [[::1]:49982] Writing something back... 181s [[::1]:49982] Telling the main thread about b'There!\n' 181s [[::1]:49982] Closing down the writer socket 181s [[::1]:49956] Telling the main thread about b'Hello 6\n' 181s [[::1]:49956] Writing something back... 181s [[::1]:49956] Telling the main thread about b'There!\n' 181s [[::1]:49956] Closing down the writer socket 181s [test_connect [::1]:6502 7] The listener acknowledged [::1]:49982 181s [test_connect [::1]:6502 7] Waiting for the server to receive it... 181s [test_connect [::1]:6502 7] Waiting for the server to send something 181s [test_connect [::1]:6502 7] Trying to receive the actual data 181s [test_connect [::1]:6502 7] Waiting for the server to close the connection 181s [test_connect [::1]:6502 6] The listener acknowledged [::1]:49956 181s [test_connect [::1]:6502 6] Waiting for the server to receive it... 181s [test_connect [::1]:6502 6] Waiting for the server to send something 181s [test_connect [::1]:6502 6] Trying to receive the actual data 181s [test_connect [::1]:6502 6] Waiting for the server to close the connection 181s [[::1]:49982] Telling the main thread done 181s [[::1]:49956] Telling the main thread done 181s [test_connect [::1]:6502 7] Waiting for an EOF on the reader socket 181s [test_connect [::1]:6502 7] Closing our writer socket, too 181s [test_connect [::1]:6502 6] Waiting for an EOF on the reader socket 181s [test_connect [::1]:6502 6] Closing our writer socket, too 181s [test_connect [::1]:6502 7] Letting the main thread know we're done 181s [test_connect [::1]:6502 7] Done 181s [test_connect [::1]:6502 6] Letting the main thread know we're done 181s [test_connect [::1]:6502 6] Done 181s [process_connections] Fetching task plain-7 181s [process_connections] Waiting for task plain-7 181s [process_connections] Done with task plain-7 181s [process_connections] Fetching task plain-6 181s [process_connections] Waiting for task plain-6 181s [process_connections] Done with task plain-6 181s [test_connect [::1]:6502 9] Sending something... 181s [test_connect [::1]:6502 9] Waiting for the main thread to figure it out 181s [[::1]:49984] Telling the main thread about b'Hello 9\n' 181s [[::1]:49984] Writing something back... 181s [[::1]:49984] Telling the main thread about b'There!\n' 181s [[::1]:49984] Closing down the writer socket 181s [test_connect [::1]:6502 9] The listener acknowledged [::1]:49984 181s [test_connect [::1]:6502 9] Waiting for the server to receive it... 181s [test_connect [::1]:6502 9] Waiting for the server to send something 181s [test_connect [::1]:6502 9] Trying to receive the actual data 181s [test_connect [::1]:6502 9] Waiting for the server to close the connection 181s [[::1]:49984] Telling the main thread done 181s [test_connect [::1]:6502 9] Waiting for an EOF on the reader socket 181s [test_connect [::1]:6502 9] Closing our writer socket, too 181s [test_connect [::1]:6502 9] Letting the main thread know we're done 181s [test_connect [::1]:6502 9] Done 181s [process_connections] Fetching task plain-9 181s [process_connections] Waiting for task plain-9 181s [process_connections] Done with task plain-9 181s [main] Picking a listen address:port for stunnel 181s [find_listening_port] Looking for a port to listen on 181s [find_listening_port] Trying ::1 port 6503 181s [find_listening_port] got 6503, shutting down the listener 181s [main] Will put stunnel at ::1:6503 181s [main] Preparing the stunnel config file 181s [main] Using config file /tmp/struntime.7vymg6fg/stunnel.conf 181s [main] 181s [main] pid = /tmp/struntime.7vymg6fg/stunnel.pid 181s [main] foreground = yes 181s [main] 181s [main] cert = /tmp/autopkgtest.hBducp/build.a1S/src/debian/tests/certs/certificate.pem 181s [main] key = /tmp/struntime.7vymg6fg/key.pem 181s [main] 181s [main] [test] 181s [main] accept = ::1:6503 181s [main] connect = ::1:6502 181s [main] End of the config file 181s [main] Launching the stunnel server 181s [main] - got pid 2357 181s [main] Launching the stunnel output pipe thread 181s [main] Waiting for stunnel to start up 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: stunnel 5.70 on powerpc64le-unknown-linux-gnu platform' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: Compiled with OpenSSL 3.0.10 1 Aug 2023' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: Running with OpenSSL 3.0.13 30 Jan 2024' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: Reading configuration from file /tmp/struntime.7vymg6fg/stunnel.conf' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: UTF-8 byte order mark not detected' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: FIPS mode disabled' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[ui]: Configuration successful' 181s [stunnel_output] The main thread ought to know 181s [main] Testing the encrypted connections 181s [test_connect [::1]:6503 0] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 1] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 2] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 3] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 4] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 5] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 6] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 7] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 8] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 9] Trying port 6503 encrypted True 181s [test_connect [::1]:6503 3] Creating an SSL context 181s [test_connect [::1]:6503 3] - cert required 181s [test_connect [::1]:6503 3] - load_verify_locations() 181s [test_connect [::1]:6503 3] Opening an SSL connection 181s [test_connect [::1]:6503 5] Creating an SSL context 181s [test_connect [::1]:6503 5] - cert required 181s [test_connect [::1]:6503 5] - load_verify_locations() 181s [test_connect [::1]:6503 5] Opening an SSL connection 181s [test_connect [::1]:6503 3] Connected to the server: [::1]:53904 181s [test_connect [::1]:6503 5] Connected to the server: [::1]:53912 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[0]: Service [test] accepted connection from ::1:53904' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[1]: Service [test] accepted connection from ::1:53912' 181s [listener] A client connected from somewhere 181s [[::1]:50000] New connection 181s [[::1]:50000] Telling the main thread 181s [[::1]:50000] Let them tell us something... 181s [listener] A client connected from somewhere 181s [[::1]:50002] New connection 181s [[::1]:50002] Telling the main thread 181s [[::1]:50002] Let them tell us something... 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[1]: s_connect: connected ::1:6502' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[1]: Service [test] connected remote server from ::1:50000' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[0]: s_connect: connected ::1:6502' 181s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[0]: Service [test] connected remote server from ::1:50002' 182s [test_connect [::1]:6503 4] Creating an SSL context 182s [test_connect [::1]:6503 4] - cert required 182s [test_connect [::1]:6503 4] - load_verify_locations() 182s [test_connect [::1]:6503 4] Opening an SSL connection 182s [test_connect [::1]:6503 4] Connected to the server: [::1]:53926 182s [test_connect [::1]:6503 7] Creating an SSL context 182s [test_connect [::1]:6503 7] - cert required 182s [test_connect [::1]:6503 7] - load_verify_locations() 182s [test_connect [::1]:6503 7] Opening an SSL connection 182s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[2]: Service [test] accepted connection from ::1:53926' 182s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[2]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:18 LOG5[2]: Service [test] connected remote server from ::1:50006' 182s [listener] A client connected from somewhere 182s [[::1]:50006] New connection 182s [[::1]:50006] Telling the main thread 182s [[::1]:50006] Let them tell us something... 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[3]: Service [test] accepted connection from ::1:53930' 182s [test_connect [::1]:6503 7] Connected to the server: [::1]:53930 182s [listener] A client connected from somewhere 182s [[::1]:50020] New connection 182s [[::1]:50020] Telling the main thread 182s [[::1]:50020] Let them tell us something... 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[3]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[3]: Service [test] connected remote server from ::1:50020' 182s [test_connect [::1]:6503 1] Creating an SSL context 182s [test_connect [::1]:6503 1] - cert required 182s [test_connect [::1]:6503 1] - load_verify_locations() 182s [test_connect [::1]:6503 1] Opening an SSL connection 182s [test_connect [::1]:6503 6] Creating an SSL context 182s [test_connect [::1]:6503 6] - cert required 182s [test_connect [::1]:6503 6] - load_verify_locations() 182s [test_connect [::1]:6503 6] Opening an SSL connection 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[4]: Service [test] accepted connection from ::1:53944' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[5]: Service [test] accepted connection from ::1:53948' 182s [test_connect [::1]:6503 1] Connected to the server: [::1]:53944 182s [test_connect [::1]:6503 6] Connected to the server: [::1]:53948 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[5]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[5]: Service [test] connected remote server from ::1:50036' 182s [listener] A client connected from somewhere 182s [[::1]:50036] New connection 182s [[::1]:50036] Telling the main thread 182s [[::1]:50036] Let them tell us something... 182s [listener] A client connected from somewhere 182s [[::1]:50044] New connection 182s [[::1]:50044] Telling the main thread 182s [[::1]:50044] Let them tell us something... 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[4]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[4]: Service [test] connected remote server from ::1:50044' 182s [test_connect [::1]:6503 9] Creating an SSL context 182s [test_connect [::1]:6503 9] - cert required 182s [test_connect [::1]:6503 9] - load_verify_locations() 182s [test_connect [::1]:6503 9] Opening an SSL connection 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[6]: Service [test] accepted connection from ::1:53954' 182s [test_connect [::1]:6503 9] Connected to the server: [::1]:53954 182s [listener] A client connected from somewhere 182s [[::1]:50052] New connection 182s [[::1]:50052] Telling the main thread 182s [[::1]:50052] Let them tell us something... 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[6]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[6]: Service [test] connected remote server from ::1:50052' 182s [test_connect [::1]:6503 5] Sending something... 182s [test_connect [::1]:6503 5] Waiting for the main thread to figure it out 182s [[::1]:50000] Telling the main thread about b'Hello 5\n' 182s [[::1]:50000] Writing something back... 182s [[::1]:50000] Telling the main thread about b'There!\n' 182s [[::1]:50000] Closing down the writer socket 182s [test_connect [::1]:6503 5] The listener acknowledged [::1]:50000 182s [test_connect [::1]:6503 5] Waiting for the server to receive it... 182s [test_connect [::1]:6503 5] Waiting for the server to send something 182s [test_connect [::1]:6503 5] Trying to receive the actual data 182s [test_connect [::1]:6503 5] Waiting for the server to close the connection 182s [[::1]:50000] Telling the main thread done 182s [test_connect [::1]:6503 5] Waiting for an EOF on the reader socket 182s [test_connect [::1]:6503 5] Closing our writer socket, too 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 182s [test_connect [::1]:6503 5] Letting the main thread know we're done 182s [test_connect [::1]:6503 5] Done 182s [process_connections] Fetching task encr-5 182s [process_connections] Waiting for task encr-5 182s [process_connections] Done with task encr-5 182s [test_connect [::1]:6503 0] Creating an SSL context 182s [test_connect [::1]:6503 0] - cert required 182s [test_connect [::1]:6503 0] - load_verify_locations() 182s [test_connect [::1]:6503 0] Opening an SSL connection 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[7]: Service [test] accepted connection from ::1:53960' 182s [test_connect [::1]:6503 0] Connected to the server: [::1]:53960 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[7]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[7]: Service [test] connected remote server from ::1:50060' 182s [listener] A client connected from somewhere 182s [[::1]:50060] New connection 182s [[::1]:50060] Telling the main thread 182s [[::1]:50060] Let them tell us something... 182s [test_connect [::1]:6503 2] Creating an SSL context 182s [test_connect [::1]:6503 2] - cert required 182s [test_connect [::1]:6503 2] - load_verify_locations() 182s [test_connect [::1]:6503 2] Opening an SSL connection 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[8]: Service [test] accepted connection from ::1:53968' 182s [test_connect [::1]:6503 8] Creating an SSL context 182s [test_connect [::1]:6503 8] - cert required 182s [test_connect [::1]:6503 8] - load_verify_locations() 182s [test_connect [::1]:6503 8] Opening an SSL connection 182s [test_connect [::1]:6503 2] Connected to the server: [::1]:53968 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[9]: Service [test] accepted connection from ::1:53972' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[8]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[8]: Service [test] connected remote server from ::1:50068' 182s [listener] A client connected from somewhere 182s [[::1]:50068] New connection 182s [[::1]:50068] Telling the main thread 182s [[::1]:50068] Let them tell us something... 182s [test_connect [::1]:6503 8] Connected to the server: [::1]:53972 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[9]: s_connect: connected ::1:6502' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[9]: Service [test] connected remote server from ::1:50074' 182s [listener] A client connected from somewhere 182s [[::1]:50074] New connection 182s [[::1]:50074] Telling the main thread 182s [[::1]:50074] Let them tell us something... 182s [test_connect [::1]:6503 0] Sending something... 182s [test_connect [::1]:6503 0] Waiting for the main thread to figure it out 182s [[::1]:50060] Telling the main thread about b'Hello 0\n' 182s [[::1]:50060] Writing something back... 182s [[::1]:50060] Telling the main thread about b'There!\n' 182s [[::1]:50060] Closing down the writer socket 182s [test_connect [::1]:6503 0] The listener acknowledged [::1]:50060 182s [test_connect [::1]:6503 0] Waiting for the server to receive it... 182s [test_connect [::1]:6503 0] Waiting for the server to send something 182s [test_connect [::1]:6503 0] Trying to receive the actual data 182s [[::1]:50060] Telling the main thread done 182s [test_connect [::1]:6503 0] Waiting for the server to close the connection 182s [test_connect [::1]:6503 0] Waiting for an EOF on the reader socket 182s [test_connect [::1]:6503 0] Closing our writer socket, too 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 182s [test_connect [::1]:6503 0] Letting the main thread know we're done 182s [test_connect [::1]:6503 0] Done 182s [process_connections] Fetching task encr-0 182s [process_connections] Waiting for task encr-0 182s [process_connections] Done with task encr-0 182s [test_connect [::1]:6503 4] Sending something... 182s [test_connect [::1]:6503 4] Waiting for the main thread to figure it out 182s [[::1]:50006] Telling the main thread about b'Hello 4\n' 182s [[::1]:50006] Writing something back... 182s [[::1]:50006] Telling the main thread about b'There!\n' 182s [[::1]:50006] Closing down the writer socket 182s [test_connect [::1]:6503 4] The listener acknowledged [::1]:50006 182s [test_connect [::1]:6503 4] Waiting for the server to receive it... 182s [test_connect [::1]:6503 4] Waiting for the server to send something 182s [test_connect [::1]:6503 4] Trying to receive the actual data 182s [test_connect [::1]:6503 4] Waiting for the server to close the connection 182s [[::1]:50006] Telling the main thread done 182s [test_connect [::1]:6503 4] Waiting for an EOF on the reader socket 182s [test_connect [::1]:6503 4] Closing our writer socket, too 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 182s [test_connect [::1]:6503 4] Letting the main thread know we're done 182s [test_connect [::1]:6503 4] Done 182s [process_connections] Fetching task encr-4 182s [process_connections] Waiting for task encr-4 182s [process_connections] Done with task encr-4 182s [test_connect [::1]:6503 3] Sending something... 182s [test_connect [::1]:6503 3] Waiting for the main thread to figure it out 182s [test_connect [::1]:6503 8] Sending something... 182s [test_connect [::1]:6503 8] Waiting for the main thread to figure it out 182s [[::1]:50002] Telling the main thread about b'Hello 3\n' 182s [[::1]:50002] Writing something back... 182s [[::1]:50002] Telling the main thread about b'There!\n' 182s [[::1]:50002] Closing down the writer socket 182s [[::1]:50074] Telling the main thread about b'Hello 8\n' 182s [[::1]:50074] Writing something back... 182s [[::1]:50074] Telling the main thread about b'There!\n' 182s [[::1]:50074] Closing down the writer socket 182s [test_connect [::1]:6503 3] The listener acknowledged [::1]:50002 182s [test_connect [::1]:6503 3] Waiting for the server to receive it... 182s [test_connect [::1]:6503 3] Waiting for the server to send something 182s [test_connect [::1]:6503 3] Trying to receive the actual data 182s [test_connect [::1]:6503 3] Waiting for the server to close the connection 182s [test_connect [::1]:6503 8] The listener acknowledged [::1]:50074 182s [test_connect [::1]:6503 8] Waiting for the server to receive it... 182s [test_connect [::1]:6503 8] Waiting for the server to send something 182s [test_connect [::1]:6503 8] Trying to receive the actual data 182s [test_connect [::1]:6503 8] Waiting for the server to close the connection 182s [[::1]:50002] Telling the main thread done 182s [[::1]:50074] Telling the main thread done 182s [test_connect [::1]:6503 3] Waiting for an EOF on the reader socket 182s [test_connect [::1]:6503 3] Closing our writer socket, too 182s [test_connect [::1]:6503 8] Waiting for an EOF on the reader socket 182s [test_connect [::1]:6503 8] Closing our writer socket, too 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 182s [stunnel_output] Read '2024.03.19 03:00:19 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 182s [test_connect [::1]:6503 3] Letting the main thread know we're done 182s [test_connect [::1]:6503 3] Done 182s [test_connect [::1]:6503 8] Letting the main thread know we're done 182s [test_connect [::1]:6503 8] Done 182s [process_connections] Fetching task encr-3 182s [process_connections] Waiting for task encr-3 182s [process_connections] Done with task encr-3 182s [process_connections] Fetching task encr-8 182s [process_connections] Waiting for task encr-8 182s [process_connections] Done with task encr-8 183s [test_connect [::1]:6503 7] Sending something... 183s [test_connect [::1]:6503 7] Waiting for the main thread to figure it out 183s [[::1]:50020] Telling the main thread about b'Hello 7\n' 183s [[::1]:50020] Writing something back... 183s [[::1]:50020] Telling the main thread about b'There!\n' 183s [[::1]:50020] Closing down the writer socket 183s [test_connect [::1]:6503 7] The listener acknowledged [::1]:50020 183s [test_connect [::1]:6503 7] Waiting for the server to receive it... 183s [test_connect [::1]:6503 7] Waiting for the server to send something 183s [test_connect [::1]:6503 7] Trying to receive the actual data 183s [[::1]:50020] Telling the main thread done 183s [test_connect [::1]:6503 7] Waiting for the server to close the connection 183s [test_connect [::1]:6503 7] Waiting for an EOF on the reader socket 183s [test_connect [::1]:6503 7] Closing our writer socket, too 183s [stunnel_output] Read '2024.03.19 03:00:20 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 183s [test_connect [::1]:6503 7] Letting the main thread know we're done 183s [test_connect [::1]:6503 7] Done 183s [process_connections] Fetching task encr-7 183s [process_connections] Waiting for task encr-7 183s [process_connections] Done with task encr-7 183s [test_connect [::1]:6503 9] Sending something... 183s [test_connect [::1]:6503 9] Waiting for the main thread to figure it out 183s [[::1]:50052] Telling the main thread about b'Hello 9\n' 183s [[::1]:50052] Writing something back... 183s [[::1]:50052] Telling the main thread about b'There!\n' 183s [[::1]:50052] Closing down the writer socket 183s [test_connect [::1]:6503 9] The listener acknowledged [::1]:50052 183s [test_connect [::1]:6503 9] Waiting for the server to receive it... 183s [test_connect [::1]:6503 9] Waiting for the server to send something 183s [test_connect [::1]:6503 9] Trying to receive the actual data 183s [test_connect [::1]:6503 9] Waiting for the server to close the connection 183s [[::1]:50052] Telling the main thread done 183s [test_connect [::1]:6503 1] Sending something... 183s [test_connect [::1]:6503 1] Waiting for the main thread to figure it out 183s [test_connect [::1]:6503 6] Sending something... 183s [test_connect [::1]:6503 6] Waiting for the main thread to figure it out 183s [test_connect [::1]:6503 9] Waiting for an EOF on the reader socket 183s [test_connect [::1]:6503 9] Closing our writer socket, too 183s [stunnel_output] Read '2024.03.19 03:00:20 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 183s [test_connect [::1]:6503 9] Letting the main thread know we're done 183s [test_connect [::1]:6503 9] Done 183s [[::1]:50036] Telling the main thread about b'Hello 6\n' 183s [[::1]:50036] Writing something back... 183s [[::1]:50036] Telling the main thread about b'There!\n' 183s [[::1]:50036] Closing down the writer socket 183s [[::1]:50044] Telling the main thread about b'Hello 1\n' 183s [[::1]:50044] Writing something back... 183s [[::1]:50044] Telling the main thread about b'There!\n' 183s [[::1]:50044] Closing down the writer socket 183s [process_connections] Fetching task encr-9 183s [process_connections] Waiting for task encr-9 183s [process_connections] Done with task encr-9 183s [test_connect [::1]:6503 6] The listener acknowledged [::1]:50036 183s [test_connect [::1]:6503 6] Waiting for the server to receive it... 183s [test_connect [::1]:6503 6] Waiting for the server to send something 183s [test_connect [::1]:6503 6] Trying to receive the actual data 183s [test_connect [::1]:6503 6] Waiting for the server to close the connection 183s [test_connect [::1]:6503 1] The listener acknowledged [::1]:50044 183s [test_connect [::1]:6503 1] Waiting for the server to receive it... 183s [test_connect [::1]:6503 1] Waiting for the server to send something 183s [test_connect [::1]:6503 1] Trying to receive the actual data 183s [test_connect [::1]:6503 1] Waiting for the server to close the connection 183s [[::1]:50036] Telling the main thread done 183s [[::1]:50044] Telling the main thread done 183s [test_connect [::1]:6503 6] Waiting for an EOF on the reader socket 183s [test_connect [::1]:6503 6] Closing our writer socket, too 183s [test_connect [::1]:6503 1] Waiting for an EOF on the reader socket 183s [test_connect [::1]:6503 1] Closing our writer socket, too 183s [stunnel_output] Read '2024.03.19 03:00:20 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 183s [test_connect [::1]:6503 6] Letting the main thread know we're done 183s [test_connect [::1]:6503 6] Done 183s [test_connect [::1]:6503 1] Letting the main thread know we're done 183s [test_connect [::1]:6503 1] Done 183s [process_connections] Fetching task encr-6 183s [process_connections] Waiting for task encr-6 183s [process_connections] Done with task encr-6 183s [process_connections] Fetching task encr-1 183s [process_connections] Waiting for task encr-1 183s [process_connections] Done with task encr-1 183s [stunnel_output] Read '2024.03.19 03:00:20 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 183s [test_connect [::1]:6503 2] Sending something... 183s [test_connect [::1]:6503 2] Waiting for the main thread to figure it out 183s [[::1]:50068] Telling the main thread about b'Hello 2\n' 183s [[::1]:50068] Writing something back... 183s [[::1]:50068] Telling the main thread about b'There!\n' 183s [[::1]:50068] Closing down the writer socket 183s [test_connect [::1]:6503 2] The listener acknowledged [::1]:50068 183s [test_connect [::1]:6503 2] Waiting for the server to receive it... 183s [test_connect [::1]:6503 2] Waiting for the server to send something 183s [test_connect [::1]:6503 2] Trying to receive the actual data 183s [test_connect [::1]:6503 2] Waiting for the server to close the connection 183s [[::1]:50068] Telling the main thread done 183s [test_connect [::1]:6503 2] Waiting for an EOF on the reader socket 183s [test_connect [::1]:6503 2] Closing our writer socket, too 183s [stunnel_output] Read '2024.03.19 03:00:20 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 183s [test_connect [::1]:6503 2] Letting the main thread know we're done 183s [test_connect [::1]:6503 2] Done 183s [process_connections] Fetching task encr-2 183s [process_connections] Waiting for task encr-2 183s [process_connections] Done with task encr-2 183s [main] Everything seems to be all right! 183s About to cancel 2 remaining task(s) 183s - listen 183s - output 183s Waiting for the tasks to hopefully finish 183s About to kill and wait for 1 child process(es) 183s - pid 2357 183s Waiting for the processes to exit... 183s Got processes' exit status: [-9] 183s autopkgtest [03:00:20]: test debian-python: -----------------------] 184s debian-python PASS 184s autopkgtest [03:00:21]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 184s autopkgtest [03:00:21]: test upstream: preparing testbed 359s autopkgtest [03:03:16]: testbed dpkg architecture: ppc64el 359s autopkgtest [03:03:16]: testbed apt version: 2.7.12 359s autopkgtest [03:03:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 360s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 361s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 361s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.0 kB] 361s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [486 kB] 362s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3753 kB] 364s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [646 kB] 364s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 364s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 364s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 364s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4025 kB] 365s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 365s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [47.3 kB] 365s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 369s Fetched 9146 kB in 6s (1658 kB/s) 369s Reading package lists... 372s Reading package lists... 373s Building dependency tree... 373s Reading state information... 373s Calculating upgrade... 374s The following packages will be REMOVED: 374s libssl3 374s The following NEW packages will be installed: 374s libssl3t64 374s The following packages will be upgraded: 374s openssl 374s 1 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 374s Need to get 3151 kB of archives. 374s After this operation, 73.7 kB of additional disk space will be used. 374s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el openssl ppc64el 3.0.13-0ubuntu2 [1026 kB] 375s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libssl3t64 ppc64el 3.0.13-0ubuntu2 [2125 kB] 376s Fetched 3151 kB in 1s (2972 kB/s) 376s (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 ... 70095 files and directories currently installed.) 376s Preparing to unpack .../openssl_3.0.13-0ubuntu2_ppc64el.deb ... 376s Unpacking openssl (3.0.13-0ubuntu2) over (3.0.10-1ubuntu4) ... 377s dpkg: libssl3:ppc64el: dependency problems, but removing anyway as you requested: 377s wget depends on libssl3 (>= 3.0.0). 377s tnftp depends on libssl3 (>= 3.0.0). 377s tcpdump depends on libssl3 (>= 3.0.0). 377s systemd-resolved depends on libssl3 (>= 3.0.0). 377s systemd depends on libssl3 (>= 3.0.0). 377s sudo depends on libssl3 (>= 3.0.0). 377s rsync depends on libssl3 (>= 3.0.0). 377s python3-cryptography depends on libssl3 (>= 3.0.0). 377s openssh-server depends on libssl3 (>= 3.0.10). 377s openssh-client depends on libssl3 (>= 3.0.10). 377s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 377s libsystemd-shared:ppc64el depends on libssl3 (>= 3.0.0). 377s libssh-4:ppc64el depends on libssl3 (>= 3.0.0). 377s libsasl2-modules:ppc64el depends on libssl3 (>= 3.0.0). 377s libsasl2-2:ppc64el depends on libssl3 (>= 3.0.0). 377s libpython3.12-minimal:ppc64el depends on libssl3 (>= 3.0.0). 377s libpython3.11-minimal:ppc64el depends on libssl3 (>= 3.0.0). 377s libnvme1 depends on libssl3 (>= 3.0.0). 377s libkrb5-3:ppc64el depends on libssl3 (>= 3.0.0). 377s libkmod2:ppc64el depends on libssl3 (>= 3.0.0). 377s libfido2-1:ppc64el depends on libssl3 (>= 3.0.0). 377s libcurl4:ppc64el depends on libssl3 (>= 3.0.0). 377s libcryptsetup12:ppc64el depends on libssl3 (>= 3.0.0). 377s kmod depends on libssl3 (>= 3.0.0). 377s dhcpcd-base depends on libssl3 (>= 3.0.0). 377s bind9-libs:ppc64el depends on libssl3 (>= 3.0.0). 377s 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 ... 70095 files and directories currently installed.) 377s Removing libssl3:ppc64el (3.0.10-1ubuntu4) ... 377s Selecting previously unselected package libssl3t64:ppc64el. 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 ... 70084 files and directories currently installed.) 377s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu2_ppc64el.deb ... 377s Unpacking libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 377s Setting up libssl3t64:ppc64el (3.0.13-0ubuntu2) ... 377s Setting up openssl (3.0.13-0ubuntu2) ... 377s Processing triggers for man-db (2.12.0-3) ... 380s Processing triggers for libc-bin (2.39-0ubuntu2) ... 380s Reading package lists... 381s Building dependency tree... 381s Reading state information... 381s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 382s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 382s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 382s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 382s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 384s Reading package lists... 384s Reading package lists... 385s Building dependency tree... 385s Reading state information... 385s Calculating upgrade... 386s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 386s Reading package lists... 386s Building dependency tree... 386s Reading state information... 386s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 391s Reading package lists... 391s Building dependency tree... 391s Reading state information... 391s Starting pkgProblemResolver with broken count: 0 391s Starting 2 pkgProblemResolver with broken count: 0 391s Done 392s The following additional packages will be installed: 392s net-tools stunnel4 392s Suggested packages: 392s logcheck-database 392s The following NEW packages will be installed: 392s autopkgtest-satdep net-tools stunnel4 392s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 392s Need to get 455 kB/456 kB of archives. 392s After this operation, 2081 kB of additional disk space will be used. 392s Get:1 /tmp/autopkgtest.hBducp/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [732 B] 392s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el stunnel4 ppc64el 3:5.70-2 [233 kB] 392s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el net-tools ppc64el 2.10-0.1ubuntu3 [223 kB] 393s Fetched 455 kB in 1s (905 kB/s) 393s Selecting previously unselected package stunnel4. 393s (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 ... 70097 files and directories currently installed.) 393s Preparing to unpack .../stunnel4_3%3a5.70-2_ppc64el.deb ... 393s Unpacking stunnel4 (3:5.70-2) ... 393s Selecting previously unselected package net-tools. 393s Preparing to unpack .../net-tools_2.10-0.1ubuntu3_ppc64el.deb ... 393s Unpacking net-tools (2.10-0.1ubuntu3) ... 393s Selecting previously unselected package autopkgtest-satdep. 393s Preparing to unpack .../2-autopkgtest-satdep.deb ... 393s Unpacking autopkgtest-satdep (0) ... 393s Setting up stunnel4 (3:5.70-2) ... 393s Creating group 'stunnel4' with GID 988. 393s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 394s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 394s Setting up net-tools (2.10-0.1ubuntu3) ... 395s Setting up autopkgtest-satdep (0) ... 395s Processing triggers for man-db (2.12.0-3) ... 400s (Reading database ... 70190 files and directories currently installed.) 400s Removing autopkgtest-satdep (0) ... 406s autopkgtest [03:04:03]: test upstream: debian/tests/upstream 406s autopkgtest [03:04:03]: test upstream: [----------------------- 408s keys & certificates successfully generated 408s ./maketestcert.sh finished 408s 011. Test "cert" option 409s ...................................................................... succeeded 409s 012. Test no "cert" option 409s ...................................................................... succeeded 409s 021. Test "requireCert" option 409s ...................................................................... succeeded 409s 022. Failure test "requireCert" option 409s ...................................................................... succeeded 409s 031. Test "verifyPeer" option 409s ...................................................................... succeeded 409s 032. Failure test "verifyPeer" option 409s ...................................................................... succeeded 409s 041. Test "verifyChain" option 409s ...................................................................... succeeded 409s 042. Failure test "verifyChain" option 409s ...................................................................... succeeded 409s 051. CRL file with valid certificate 409s ...................................................................... succeeded 409s 052. CRL file with revoked certificate 409s ...................................................................... succeeded 409s 061. Test "PSKsecrets" option 409s ...................................................................... succeeded 409s 062. Failure test "PSKsecrets" option 409s ...................................................................... succeeded 409s 071. Test PKCS#12 certificate 409s ...................................................................... succeeded 409s 081. Test IPv6 support 409s ...................................................................... succeeded 409s 091. Binding server 409s ...................................................................... succeeded 409s 101. Test FIPS mode 409s ...................................................................... skipped 409s 111. Failure FIPS mode with unavailable cipher 409s ...................................................................... skipped 409s 112. Failure FIPS mode with unavailable ciphersuite 409s ...................................................................... skipped 409s 113. Failure FIPS mode with unavailable elliptic curve 409s ...................................................................... skipped 409s 121. Test "sni" option 409s ...................................................................... succeeded 409s 122. Failure test "sni" option 410s ...................................................................... succeeded 410s 131. Resume session with PSK authentication 410s ...................................................................... succeeded 410s 132. Cache session resumption with TLSv1.2 410s ...................................................................... succeeded 410s 133. Test enabled "sessionResume" option 410s ...................................................................... succeeded 410s 134. Test disabled "sessionResume" option 410s ...................................................................... succeeded 410s 141. Stateless session ticket resumption with TLSv1.2 411s ...................................................................... succeeded 411s 142. Stateless session ticket resumption with TLSv1.3 411s ...................................................................... succeeded 411s 151. Session resumption with secret keys 412s ...................................................................... succeeded 412s 161. Redirect connection (wrong peer certificate) 412s ...................................................................... succeeded 412s 162. Redirect connection (no peer certificate) 412s ...................................................................... succeeded 412s 163. Not redirected connection (valid peer certificate) 412s ...................................................................... succeeded 412s 164. Redirect connection (wrong chain) 412s ...................................................................... succeeded 412s 165. Redirect connection (no chain) 412s ...................................................................... succeeded 412s 166. Not redirected connection (valid chain) 412s ...................................................................... succeeded 412s 171. Redirect proxy connection (wrong peer certificate) 412s ...................................................................... succeeded 412s 172. Redirect proxy connection (no peer certificate) 413s ...................................................................... succeeded 413s 173. Not redirected proxy connection (valid peer certificate) 413s ...................................................................... succeeded 413s 181. Session resumption and redirect (wrong certificate) 413s ...................................................................... succeeded 413s 182. Session resumption and redirect (no certificate) 413s ...................................................................... succeeded 413s 183. Session resumption and no redirect (valid certificate) 413s ...................................................................... succeeded 413s 191. Reload configuration 413s ...................................................................... succeeded 413s 201. Failover round robin (rr) strategy 414s ...................................................................... succeeded 414s 202. Failover priority (prio) strategy 414s ...................................................................... succeeded 414s 211. Included configuration files 414s ...................................................................... succeeded 414s 221. Client inetd mode 414s ...................................................................... succeeded 414s 231. Test exec+connect service 414s ...................................................................... succeeded 414s 241. Resume session with delay option 415s ...................................................................... succeeded 415s 242. Resume session with disable delay option 415s ...................................................................... succeeded 415s 251. Wrong client configuration 415s ...................................................................... succeeded 415s 261. Wrong server configuration 415s ...................................................................... succeeded 415s 415s Summary: 415s succeeded: 46 415s failed: 0 415s skipped: 4 415s 415s Testing results: /tmp/autopkgtest.hBducp/build.a1S/src/tests/logs/results.log 416s autopkgtest [03:04:13]: test upstream: -----------------------] 416s autopkgtest [03:04:13]: test upstream: - - - - - - - - - - results - - - - - - - - - - 416s upstream PASS 417s autopkgtest [03:04:14]: @@@@@@@@@@@@@@@@@@@@ summary 417s debian-python PASS 417s upstream PASS 431s Creating nova instance adt-noble-ppc64el-stunnel4-20240319-025716-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240319.img (UUID 9d87beed-6c32-481f-958d-7469e26317f3)... 431s Creating nova instance adt-noble-ppc64el-stunnel4-20240319-025716-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240319.img (UUID 9d87beed-6c32-481f-958d-7469e26317f3)...