0s autopkgtest [15:53:14]: starting date and time: 2024-11-13 15:53:14+0000
  0s autopkgtest [15:53:14]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky
  0s autopkgtest [15:53:14]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3624uhqe/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.7-1 python3-stdlib-extensions/3.12.7-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-14.secgroup --name adt-plucky-ppc64el-stunnel4-20241113-144935-juju-7f2275-prod-proposed-migration-environment-20-67c04338-af79-4d03-856c-f1e4abaf141d --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -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,keyserver.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/
 94s autopkgtest [15:54:48]: testbed dpkg architecture: ppc64el
 94s autopkgtest [15:54:48]: testbed apt version: 2.9.8
 94s autopkgtest [15:54:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 95s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB]
 95s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [104 kB]
 96s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B]
 96s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.2 kB]
 96s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [950 kB]
 96s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [111 kB]
 96s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [660 kB]
 96s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [20.8 kB]
 96s Fetched 1944 kB in 1s (1726 kB/s)
 96s Reading package lists...
 99s Reading package lists...
 99s Building dependency tree...
 99s Reading state information...
100s Calculating upgrade...
100s The following NEW packages will be installed:
100s   python3.13-gdbm
100s The following packages will be upgraded:
100s   libgnutls30t64 libjson-glib-1.0-0 libjson-glib-1.0-common libpython3-stdlib
100s   libutempter0 python3 python3-gdbm python3-minimal
100s 8 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
100s Need to get 1265 kB of archives.
100s After this operation, 141 kB of additional disk space will be used.
100s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-minimal ppc64el 3.12.7-1 [27.4 kB]
101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3 ppc64el 3.12.7-1 [24.0 kB]
101s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpython3-stdlib ppc64el 3.12.7-1 [10.0 kB]
101s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgnutls30t64 ppc64el 3.8.8-2ubuntu1 [1072 kB]
101s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.13-gdbm ppc64el 3.13.0-2 [31.5 kB]
101s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-gdbm ppc64el 3.12.7-1 [8640 B]
101s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-common all 1.10.0+ds-3 [5586 B]
101s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-0 ppc64el 1.10.0+ds-3 [76.0 kB]
101s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libutempter0 ppc64el 1.2.1-4 [9850 B]
101s Fetched 1265 kB in 1s (1931 kB/s)
102s (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 ... 73767 files and directories currently installed.)
102s Preparing to unpack .../python3-minimal_3.12.7-1_ppc64el.deb ...
102s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ...
102s Setting up python3-minimal (3.12.7-1) ...
102s (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 ... 73767 files and directories currently installed.)
102s Preparing to unpack .../python3_3.12.7-1_ppc64el.deb ...
102s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ...
103s Preparing to unpack .../libpython3-stdlib_3.12.7-1_ppc64el.deb ...
103s Unpacking libpython3-stdlib:ppc64el (3.12.7-1) over (3.12.6-0ubuntu1) ...
103s Preparing to unpack .../libgnutls30t64_3.8.8-2ubuntu1_ppc64el.deb ...
103s Unpacking libgnutls30t64:ppc64el (3.8.8-2ubuntu1) over (3.8.6-2ubuntu1) ...
103s Setting up libgnutls30t64:ppc64el (3.8.8-2ubuntu1) ...
103s Selecting previously unselected package python3.13-gdbm.
103s (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 ... 73767 files and directories currently installed.)
103s Preparing to unpack .../python3.13-gdbm_3.13.0-2_ppc64el.deb ...
103s Unpacking python3.13-gdbm (3.13.0-2) ...
103s Preparing to unpack .../python3-gdbm_3.12.7-1_ppc64el.deb ...
103s Unpacking python3-gdbm:ppc64el (3.12.7-1) over (3.12.6-1ubuntu1) ...
103s Preparing to unpack .../libjson-glib-1.0-common_1.10.0+ds-3_all.deb ...
103s Unpacking libjson-glib-1.0-common (1.10.0+ds-3) over (1.10.0+ds-2) ...
103s Preparing to unpack .../libjson-glib-1.0-0_1.10.0+ds-3_ppc64el.deb ...
103s Unpacking libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) over (1.10.0+ds-2) ...
103s Preparing to unpack .../libutempter0_1.2.1-4_ppc64el.deb ...
103s Unpacking libutempter0:ppc64el (1.2.1-4) over (1.2.1-3build1) ...
103s Setting up libutempter0:ppc64el (1.2.1-4) ...
103s Setting up libjson-glib-1.0-common (1.10.0+ds-3) ...
103s Setting up python3.13-gdbm (3.13.0-2) ...
103s Setting up libpython3-stdlib:ppc64el (3.12.7-1) ...
103s Setting up python3 (3.12.7-1) ...
103s Setting up libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) ...
103s Setting up python3-gdbm:ppc64el (3.12.7-1) ...
103s Processing triggers for man-db (2.12.1-3) ...
105s Processing triggers for libc-bin (2.40-1ubuntu3) ...
105s Reading package lists...
105s Building dependency tree...
105s Reading state information...
106s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
106s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease
106s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
106s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
106s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
107s Reading package lists...
107s Reading package lists...
108s Building dependency tree...
108s Reading state information...
108s Calculating upgrade...
108s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
108s Reading package lists...
108s Building dependency tree...
108s Reading state information...
108s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
112s autopkgtest [15:55:06]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024
112s autopkgtest [15:55:06]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4
114s Get:1 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.73-1 (dsc) [2845 B]
114s Get:2 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.73-1 (tar) [907 kB]
114s Get:3 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.73-1 (asc) [833 B]
114s Get:4 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.73-1 (diff) [54.6 kB]
114s gpgv: Signature made Thu Oct 24 09:16:08 2024 UTC
114s gpgv:                using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13
114s gpgv:                issuer "roam@debian.org"
114s gpgv: Can't check signature: No public key
114s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.73-1.dsc: no acceptable signature found
114s autopkgtest [15:55:08]: testing package stunnel4 version 3:5.73-1
115s autopkgtest [15:55:09]: build not needed
115s autopkgtest [15:55:09]: test debian-python: preparing testbed
119s Reading package lists...
120s Building dependency tree...
120s Reading state information...
120s Starting pkgProblemResolver with broken count: 0
120s Starting 2 pkgProblemResolver with broken count: 0
120s Done
121s The following additional packages will be installed:
121s   libpython3.13-minimal libpython3.13-stdlib python3-all python3.13
121s   python3.13-minimal stunnel4
121s Suggested packages:
121s   python3.13-venv python3.13-doc binfmt-support logcheck-database
121s The following NEW packages will be installed:
121s   autopkgtest-satdep libpython3.13-minimal libpython3.13-stdlib python3-all
121s   python3.13 python3.13-minimal stunnel4
121s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded.
121s Need to get 6288 kB/6289 kB of archives.
121s After this operation, 26.5 MB of additional disk space will be used.
121s Get:1 /tmp/autopkgtest.FhbnhQ/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B]
121s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.13-minimal ppc64el 3.13.0-2 [881 kB]
121s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.13-minimal ppc64el 3.13.0-2 [2302 kB]
122s Get:4 http://ftpmaster.internal/ubuntu plucky/universe ppc64el stunnel4 ppc64el 3:5.73-1 [237 kB]
122s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.13-stdlib ppc64el 3.13.0-2 [2148 kB]
122s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.13 ppc64el 3.13.0-2 [719 kB]
122s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-all ppc64el 3.12.7-1 [888 B]
122s Fetched 6288 kB in 1s (4841 kB/s)
123s Selecting previously unselected package libpython3.13-minimal:ppc64el.
123s (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 ... 73774 files and directories currently installed.)
123s Preparing to unpack .../0-libpython3.13-minimal_3.13.0-2_ppc64el.deb ...
123s Unpacking libpython3.13-minimal:ppc64el (3.13.0-2) ...
123s Selecting previously unselected package python3.13-minimal.
123s Preparing to unpack .../1-python3.13-minimal_3.13.0-2_ppc64el.deb ...
123s Unpacking python3.13-minimal (3.13.0-2) ...
123s Selecting previously unselected package stunnel4.
123s Preparing to unpack .../2-stunnel4_3%3a5.73-1_ppc64el.deb ...
123s Unpacking stunnel4 (3:5.73-1) ...
123s Selecting previously unselected package libpython3.13-stdlib:ppc64el.
123s Preparing to unpack .../3-libpython3.13-stdlib_3.13.0-2_ppc64el.deb ...
123s Unpacking libpython3.13-stdlib:ppc64el (3.13.0-2) ...
123s Selecting previously unselected package python3.13.
123s Preparing to unpack .../4-python3.13_3.13.0-2_ppc64el.deb ...
123s Unpacking python3.13 (3.13.0-2) ...
123s Selecting previously unselected package python3-all.
123s Preparing to unpack .../5-python3-all_3.12.7-1_ppc64el.deb ...
123s Unpacking python3-all (3.12.7-1) ...
123s Selecting previously unselected package autopkgtest-satdep.
123s Preparing to unpack .../6-1-autopkgtest-satdep.deb ...
123s Unpacking autopkgtest-satdep (0) ...
123s Setting up stunnel4 (3:5.73-1) ...
123s Creating group 'stunnel4' with GID 988.

123s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988.

124s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'.

124s Setting up libpython3.13-minimal:ppc64el (3.13.0-2) ...
124s Setting up python3.13-minimal (3.13.0-2) ...
125s Setting up libpython3.13-stdlib:ppc64el (3.13.0-2) ...
125s Setting up python3.13 (3.13.0-2) ...
127s Setting up python3-all (3.12.7-1) ...
127s Setting up autopkgtest-satdep (0) ...
127s Processing triggers for man-db (2.12.1-3) ...
129s Processing triggers for systemd (256.5-2ubuntu4) ...
132s (Reading database ... 74553 files and directories currently installed.)
132s Removing autopkgtest-satdep (0) ...
132s autopkgtest [15:55:26]: 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
132s autopkgtest [15:55:26]: test debian-python: [-----------------------
133s 
133s 
133s ====== python3.12
133s 
133s Using /tmp/struntime.ek6c5dll as a temporary directory
133s Trying to obtain the version of /usr/bin/stunnel4
133s Started `/usr/bin/stunnel4 -version` as process 2518
133s Got stunnel version 5.73
133s [main] Starting to do things
133s [main] Awaiting the 'listener started' event
133s [find_listening_port] Looking for a port to listen on
133s [find_listening_port] Trying localhost port 6502
133s [listener] Telling the main thread about [127.0.0.1]:6502
133s [listener] Awaiting client connections...
133s [main] Apparently we are listening on [127.0.0.1]:6502
133s [main] Testing cleartext connections
133s [test_connect [127.0.0.1]:6502 0] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 1] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 2] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 3] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 4] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 5] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 6] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 7] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 8] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 9] Trying port 6502 encrypted False
133s [test_connect [127.0.0.1]:6502 1] Opening an unencrypted connection
133s [listener] A client connected from somewhere
133s [[127.0.0.1]:33668] New connection
133s [[127.0.0.1]:33668] Telling the main thread
133s [[127.0.0.1]:33668] Let them tell us something...
133s [test_connect [127.0.0.1]:6502 1] Connected to the server: [127.0.0.1]:33668
134s [test_connect [127.0.0.1]:6502 6] Opening an unencrypted connection
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33676] New connection
134s [[127.0.0.1]:33676] Telling the main thread
134s [[127.0.0.1]:33676] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 6] Connected to the server: [127.0.0.1]:33676
134s [test_connect [127.0.0.1]:6502 2] Opening an unencrypted connection
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33688] New connection
134s [[127.0.0.1]:33688] Telling the main thread
134s [[127.0.0.1]:33688] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 2] Connected to the server: [127.0.0.1]:33688
134s [test_connect [127.0.0.1]:6502 1] Sending something...
134s [test_connect [127.0.0.1]:6502 1] Waiting for the main thread to figure it out
134s [[127.0.0.1]:33668] Telling the main thread about b'Hello 1\n'
134s [[127.0.0.1]:33668] Writing something back...
134s [[127.0.0.1]:33668] Telling the main thread about b'There!\n'
134s [[127.0.0.1]:33668] Closing down the writer socket
134s [test_connect [127.0.0.1]:6502 1] The listener acknowledged [127.0.0.1]:33668
134s [test_connect [127.0.0.1]:6502 1] Waiting for the server to receive it...
134s [test_connect [127.0.0.1]:6502 1] Waiting for the server to send something
134s [test_connect [127.0.0.1]:6502 1] Trying to receive the actual data
134s [test_connect [127.0.0.1]:6502 1] Waiting for the server to close the connection
134s [[127.0.0.1]:33668] Telling the main thread done
134s [test_connect [127.0.0.1]:6502 1] Waiting for an EOF on the reader socket
134s [test_connect [127.0.0.1]:6502 1] Closing our writer socket, too
134s [test_connect [127.0.0.1]:6502 1] Letting the main thread know we're done
134s [test_connect [127.0.0.1]:6502 1] Done
134s [process_connections] Fetching task plain-1
134s [process_connections] Waiting for task plain-1
134s [process_connections] Done with task plain-1
134s [test_connect [127.0.0.1]:6502 4] Opening an unencrypted connection
134s [test_connect [127.0.0.1]:6502 7] Opening an unencrypted connection
134s [test_connect [127.0.0.1]:6502 9] Opening an unencrypted connection
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33704] New connection
134s [[127.0.0.1]:33704] Telling the main thread
134s [[127.0.0.1]:33704] Let them tell us something...
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33706] New connection
134s [[127.0.0.1]:33706] Telling the main thread
134s [[127.0.0.1]:33706] Let them tell us something...
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33720] New connection
134s [[127.0.0.1]:33720] Telling the main thread
134s [[127.0.0.1]:33720] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 4] Connected to the server: [127.0.0.1]:33704
134s [test_connect [127.0.0.1]:6502 7] Connected to the server: [127.0.0.1]:33706
134s [test_connect [127.0.0.1]:6502 9] Connected to the server: [127.0.0.1]:33720
134s [test_connect [127.0.0.1]:6502 8] Opening an unencrypted connection
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33726] New connection
134s [[127.0.0.1]:33726] Telling the main thread
134s [[127.0.0.1]:33726] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 8] Connected to the server: [127.0.0.1]:33726
134s [test_connect [127.0.0.1]:6502 0] Opening an unencrypted connection
134s [test_connect [127.0.0.1]:6502 3] Opening an unencrypted connection
134s [test_connect [127.0.0.1]:6502 5] Opening an unencrypted connection
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33742] New connection
134s [[127.0.0.1]:33742] Telling the main thread
134s [[127.0.0.1]:33742] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 0] Connected to the server: [127.0.0.1]:33742
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33748] New connection
134s [[127.0.0.1]:33748] Telling the main thread
134s [[127.0.0.1]:33748] Let them tell us something...
134s [listener] A client connected from somewhere
134s [[127.0.0.1]:33762] New connection
134s [[127.0.0.1]:33762] Telling the main thread
134s [[127.0.0.1]:33762] Let them tell us something...
134s [test_connect [127.0.0.1]:6502 3] Connected to the server: [127.0.0.1]:33748
134s [test_connect [127.0.0.1]:6502 5] Connected to the server: [127.0.0.1]:33762
134s [test_connect [127.0.0.1]:6502 7] Sending something...
134s [test_connect [127.0.0.1]:6502 7] Waiting for the main thread to figure it out
134s [[127.0.0.1]:33706] Telling the main thread about b'Hello 7\n'
134s [[127.0.0.1]:33706] Writing something back...
134s [[127.0.0.1]:33706] Telling the main thread about b'There!\n'
134s [[127.0.0.1]:33706] Closing down the writer socket
134s [test_connect [127.0.0.1]:6502 7] The listener acknowledged [127.0.0.1]:33706
134s [test_connect [127.0.0.1]:6502 7] Waiting for the server to receive it...
134s [test_connect [127.0.0.1]:6502 7] Waiting for the server to send something
134s [test_connect [127.0.0.1]:6502 7] Trying to receive the actual data
134s [test_connect [127.0.0.1]:6502 7] Waiting for the server to close the connection
134s [[127.0.0.1]:33706] Telling the main thread done
134s [test_connect [127.0.0.1]:6502 7] Waiting for an EOF on the reader socket
134s [test_connect [127.0.0.1]:6502 7] Closing our writer socket, too
134s [test_connect [127.0.0.1]:6502 7] Letting the main thread know we're done
134s [test_connect [127.0.0.1]:6502 7] Done
134s [process_connections] Fetching task plain-7
134s [process_connections] Waiting for task plain-7
134s [process_connections] Done with task plain-7
134s [test_connect [127.0.0.1]:6502 2] Sending something...
134s [test_connect [127.0.0.1]:6502 2] Waiting for the main thread to figure it out
134s [[127.0.0.1]:33688] Telling the main thread about b'Hello 2\n'
134s [[127.0.0.1]:33688] Writing something back...
134s [[127.0.0.1]:33688] Telling the main thread about b'There!\n'
134s [[127.0.0.1]:33688] Closing down the writer socket
134s [test_connect [127.0.0.1]:6502 2] The listener acknowledged [127.0.0.1]:33688
134s [test_connect [127.0.0.1]:6502 2] Waiting for the server to receive it...
134s [test_connect [127.0.0.1]:6502 2] Waiting for the server to send something
134s [test_connect [127.0.0.1]:6502 2] Trying to receive the actual data
134s [test_connect [127.0.0.1]:6502 2] Waiting for the server to close the connection
134s [[127.0.0.1]:33688] Telling the main thread done
134s [test_connect [127.0.0.1]:6502 6] Sending something...
134s [test_connect [127.0.0.1]:6502 6] Waiting for the main thread to figure it out
134s [test_connect [127.0.0.1]:6502 2] Waiting for an EOF on the reader socket
134s [test_connect [127.0.0.1]:6502 2] Closing our writer socket, too
134s [[127.0.0.1]:33676] Telling the main thread about b'Hello 6\n'
134s [[127.0.0.1]:33676] Writing something back...
134s [[127.0.0.1]:33676] Telling the main thread about b'There!\n'
134s [[127.0.0.1]:33676] Closing down the writer socket
134s [test_connect [127.0.0.1]:6502 2] Letting the main thread know we're done
134s [test_connect [127.0.0.1]:6502 2] Done
134s [process_connections] Fetching task plain-2
134s [process_connections] Waiting for task plain-2
134s [process_connections] Done with task plain-2
134s [test_connect [127.0.0.1]:6502 6] The listener acknowledged [127.0.0.1]:33676
134s [test_connect [127.0.0.1]:6502 6] Waiting for the server to receive it...
134s [test_connect [127.0.0.1]:6502 6] Waiting for the server to send something
134s [test_connect [127.0.0.1]:6502 6] Trying to receive the actual data
134s [test_connect [127.0.0.1]:6502 6] Waiting for the server to close the connection
134s [[127.0.0.1]:33676] Telling the main thread done
134s [test_connect [127.0.0.1]:6502 6] Waiting for an EOF on the reader socket
134s [test_connect [127.0.0.1]:6502 6] Closing our writer socket, too
134s [test_connect [127.0.0.1]:6502 6] Letting the main thread know we're done
134s [test_connect [127.0.0.1]:6502 6] Done
134s [process_connections] Fetching task plain-6
134s [process_connections] Waiting for task plain-6
134s [process_connections] Done with task plain-6
135s [test_connect [127.0.0.1]:6502 3] Sending something...
135s [test_connect [127.0.0.1]:6502 3] Waiting for the main thread to figure it out
135s [[127.0.0.1]:33748] Telling the main thread about b'Hello 3\n'
135s [[127.0.0.1]:33748] Writing something back...
135s [[127.0.0.1]:33748] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33748] Closing down the writer socket
135s [test_connect [127.0.0.1]:6502 3] The listener acknowledged [127.0.0.1]:33748
135s [test_connect [127.0.0.1]:6502 3] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 3] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 3] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 3] Waiting for the server to close the connection
135s [[127.0.0.1]:33748] Telling the main thread done
135s [test_connect [127.0.0.1]:6502 3] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 3] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 3] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 3] Done
135s [process_connections] Fetching task plain-3
135s [process_connections] Waiting for task plain-3
135s [process_connections] Done with task plain-3
135s [test_connect [127.0.0.1]:6502 8] Sending something...
135s [test_connect [127.0.0.1]:6502 8] Waiting for the main thread to figure it out
135s [[127.0.0.1]:33726] Telling the main thread about b'Hello 8\n'
135s [[127.0.0.1]:33726] Writing something back...
135s [[127.0.0.1]:33726] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33726] Closing down the writer socket
135s [test_connect [127.0.0.1]:6502 8] The listener acknowledged [127.0.0.1]:33726
135s [test_connect [127.0.0.1]:6502 8] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 8] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 8] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 8] Waiting for the server to close the connection
135s [[127.0.0.1]:33726] Telling the main thread done
135s [test_connect [127.0.0.1]:6502 8] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 8] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 8] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 8] Done
135s [process_connections] Fetching task plain-8
135s [process_connections] Waiting for task plain-8
135s [process_connections] Done with task plain-8
135s [test_connect [127.0.0.1]:6502 5] Sending something...
135s [test_connect [127.0.0.1]:6502 5] Waiting for the main thread to figure it out
135s [[127.0.0.1]:33762] Telling the main thread about b'Hello 5\n'
135s [[127.0.0.1]:33762] Writing something back...
135s [[127.0.0.1]:33762] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33762] Closing down the writer socket
135s [test_connect [127.0.0.1]:6502 5] The listener acknowledged [127.0.0.1]:33762
135s [test_connect [127.0.0.1]:6502 5] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 5] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 5] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 5] Waiting for the server to close the connection
135s [[127.0.0.1]:33762] Telling the main thread done
135s [test_connect [127.0.0.1]:6502 5] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 5] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 5] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 5] Done
135s [process_connections] Fetching task plain-5
135s [process_connections] Waiting for task plain-5
135s [process_connections] Done with task plain-5
135s [test_connect [127.0.0.1]:6502 4] Sending something...
135s [test_connect [127.0.0.1]:6502 4] Waiting for the main thread to figure it out
135s [test_connect [127.0.0.1]:6502 9] Sending something...
135s [test_connect [127.0.0.1]:6502 9] Waiting for the main thread to figure it out
135s [[127.0.0.1]:33704] Telling the main thread about b'Hello 4\n'
135s [[127.0.0.1]:33704] Writing something back...
135s [[127.0.0.1]:33704] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33704] Closing down the writer socket
135s [[127.0.0.1]:33720] Telling the main thread about b'Hello 9\n'
135s [[127.0.0.1]:33720] Writing something back...
135s [[127.0.0.1]:33720] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33720] Closing down the writer socket
135s [test_connect [127.0.0.1]:6502 4] The listener acknowledged [127.0.0.1]:33704
135s [test_connect [127.0.0.1]:6502 4] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 4] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 4] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 4] Waiting for the server to close the connection
135s [test_connect [127.0.0.1]:6502 9] The listener acknowledged [127.0.0.1]:33720
135s [test_connect [127.0.0.1]:6502 9] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 9] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 9] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 9] Waiting for the server to close the connection
135s [[127.0.0.1]:33704] Telling the main thread done
135s [[127.0.0.1]:33720] Telling the main thread done
135s [test_connect [127.0.0.1]:6502 4] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 4] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 9] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 9] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 4] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 4] Done
135s [test_connect [127.0.0.1]:6502 9] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 9] Done
135s [process_connections] Fetching task plain-4
135s [process_connections] Waiting for task plain-4
135s [process_connections] Done with task plain-4
135s [process_connections] Fetching task plain-9
135s [process_connections] Waiting for task plain-9
135s [process_connections] Done with task plain-9
135s [test_connect [127.0.0.1]:6502 0] Sending something...
135s [test_connect [127.0.0.1]:6502 0] Waiting for the main thread to figure it out
135s [[127.0.0.1]:33742] Telling the main thread about b'Hello 0\n'
135s [[127.0.0.1]:33742] Writing something back...
135s [[127.0.0.1]:33742] Telling the main thread about b'There!\n'
135s [[127.0.0.1]:33742] Closing down the writer socket
135s [test_connect [127.0.0.1]:6502 0] The listener acknowledged [127.0.0.1]:33742
135s [test_connect [127.0.0.1]:6502 0] Waiting for the server to receive it...
135s [test_connect [127.0.0.1]:6502 0] Waiting for the server to send something
135s [test_connect [127.0.0.1]:6502 0] Trying to receive the actual data
135s [test_connect [127.0.0.1]:6502 0] Waiting for the server to close the connection
135s [[127.0.0.1]:33742] Telling the main thread done
135s [test_connect [127.0.0.1]:6502 0] Waiting for an EOF on the reader socket
135s [test_connect [127.0.0.1]:6502 0] Closing our writer socket, too
135s [test_connect [127.0.0.1]:6502 0] Letting the main thread know we're done
135s [test_connect [127.0.0.1]:6502 0] Done
135s [process_connections] Fetching task plain-0
135s [process_connections] Waiting for task plain-0
135s [process_connections] Done with task plain-0
135s [main] Picking a listen address:port for stunnel
135s [find_listening_port] Looking for a port to listen on
135s [find_listening_port] Trying 127.0.0.1 port 6503
135s [find_listening_port] got 6503, shutting down the listener
135s [main] Will put stunnel at 127.0.0.1:6503
135s [main] Preparing the stunnel config file
135s [main] Using config file /tmp/struntime.ek6c5dll/stunnel.conf
135s [main] 
135s [main] pid = /tmp/struntime.ek6c5dll/stunnel.pid
135s [main] foreground = yes
135s [main] 
135s [main] cert = /tmp/autopkgtest.FhbnhQ/build.W5m/src/debian/tests/certs/certificate.pem
135s [main] key = /tmp/struntime.ek6c5dll/key.pem
135s [main] 
135s [main] [test]
135s [main] accept = 127.0.0.1:6503
135s [main] connect = 127.0.0.1:6502
135s [main] End of the config file
135s [main] Launching the stunnel server
135s [main] - got pid 2522
135s [main] Launching the stunnel output pipe thread
135s [main] Waiting for stunnel to start up
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: stunnel 5.73 on powerpc64le-unknown-linux-gnu platform'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: Compiled/running with OpenSSL 3.3.1 4 Jun 2024'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: Reading configuration from file /tmp/struntime.ek6c5dll/stunnel.conf'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: UTF-8 byte order mark not detected'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: FIPS mode disabled'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[ui]: Configuration successful'
135s [stunnel_output] The main thread ought to know
135s [main] Testing the encrypted connections
135s [test_connect [127.0.0.1]:6503 0] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 1] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 2] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 3] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 4] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 5] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 6] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 7] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 8] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 9] Trying port 6503 encrypted True
135s [test_connect [127.0.0.1]:6503 2] Creating an SSL context
135s [test_connect [127.0.0.1]:6503 2] - cert required
135s [test_connect [127.0.0.1]:6503 2] - load_verify_locations()
135s [test_connect [127.0.0.1]:6503 2] Opening an SSL connection
135s [test_connect [127.0.0.1]:6503 4] Creating an SSL context
135s [test_connect [127.0.0.1]:6503 4] - cert required
135s [test_connect [127.0.0.1]:6503 4] - load_verify_locations()
135s [test_connect [127.0.0.1]:6503 4] Opening an SSL connection
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[1]: Service [test] accepted connection from 127.0.0.1:40946'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[0]: Service [test] accepted connection from 127.0.0.1:40932'
135s [test_connect [127.0.0.1]:6503 2] Connected to the server: [127.0.0.1]:40932
135s [test_connect [127.0.0.1]:6503 4] Connected to the server: [127.0.0.1]:40946
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[0]: s_connect: connected 127.0.0.1:6502'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[0]: Service [test] connected remote server from 127.0.0.1:33766'
135s [listener] A client connected from somewhere
135s [[127.0.0.1]:33766] New connection
135s [[127.0.0.1]:33766] Telling the main thread
135s [[127.0.0.1]:33766] Let them tell us something...
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[1]: s_connect: connected 127.0.0.1:6502'
135s [stunnel_output] Read '2024.11.13 15:55:29 LOG5[1]: Service [test] connected remote server from 127.0.0.1:33782'
135s [listener] A client connected from somewhere
135s [[127.0.0.1]:33782] New connection
135s [[127.0.0.1]:33782] Telling the main thread
135s [[127.0.0.1]:33782] Let them tell us something...
136s [test_connect [127.0.0.1]:6503 4] Sending something...
136s [test_connect [127.0.0.1]:6503 4] Waiting for the main thread to figure it out
136s [[127.0.0.1]:33782] Telling the main thread about b'Hello 4\n'
136s [[127.0.0.1]:33782] Writing something back...
136s [[127.0.0.1]:33782] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:33782] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 4] The listener acknowledged [127.0.0.1]:33782
136s [test_connect [127.0.0.1]:6503 4] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 4] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 4] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 4] Waiting for the server to close the connection
136s [[127.0.0.1]:33782] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 4] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 4] Closing our writer socket, too
136s [test_connect [127.0.0.1]:6503 4] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 4] Done
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
136s [process_connections] Fetching task encr-4
136s [process_connections] Waiting for task encr-4
136s [process_connections] Done with task encr-4
136s [test_connect [127.0.0.1]:6503 7] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 7] - cert required
136s [test_connect [127.0.0.1]:6503 7] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 7] Opening an SSL connection
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[2]: Service [test] accepted connection from 127.0.0.1:40962'
136s [test_connect [127.0.0.1]:6503 7] Connected to the server: [127.0.0.1]:40962
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[2]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[2]: Service [test] connected remote server from 127.0.0.1:33792'
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33792] New connection
136s [[127.0.0.1]:33792] Telling the main thread
136s [[127.0.0.1]:33792] Let them tell us something...
136s [test_connect [127.0.0.1]:6503 0] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 0] - cert required
136s [test_connect [127.0.0.1]:6503 0] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 0] Opening an SSL connection
136s [test_connect [127.0.0.1]:6503 3] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 3] - cert required
136s [test_connect [127.0.0.1]:6503 3] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 3] Opening an SSL connection
136s [test_connect [127.0.0.1]:6503 6] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 6] - cert required
136s [test_connect [127.0.0.1]:6503 6] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 6] Opening an SSL connection
136s [test_connect [127.0.0.1]:6503 8] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 8] - cert required
136s [test_connect [127.0.0.1]:6503 8] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 8] Opening an SSL connection
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[3]: Service [test] accepted connection from 127.0.0.1:40964'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[4]: Service [test] accepted connection from 127.0.0.1:40980'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[5]: Service [test] accepted connection from 127.0.0.1:40990'
136s [test_connect [127.0.0.1]:6503 0] Connected to the server: [127.0.0.1]:40964
136s [test_connect [127.0.0.1]:6503 3] Connected to the server: [127.0.0.1]:40980
136s [test_connect [127.0.0.1]:6503 6] Connected to the server: [127.0.0.1]:40990
136s [test_connect [127.0.0.1]:6503 8] Connected to the server: [127.0.0.1]:41000
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[6]: Service [test] accepted connection from 127.0.0.1:41000'
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33804] New connection
136s [[127.0.0.1]:33804] Telling the main thread
136s [[127.0.0.1]:33804] Let them tell us something...
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[6]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[3]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[3]: Service [test] connected remote server from 127.0.0.1:33816'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[5]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[6]: Service [test] connected remote server from 127.0.0.1:33804'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[5]: Service [test] connected remote server from 127.0.0.1:33828'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[4]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[4]: Service [test] connected remote server from 127.0.0.1:33836'
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33816] New connection
136s [[127.0.0.1]:33816] Telling the main thread
136s [[127.0.0.1]:33816] Let them tell us something...
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33828] New connection
136s [[127.0.0.1]:33828] Telling the main thread
136s [[127.0.0.1]:33828] Let them tell us something...
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33836] New connection
136s [[127.0.0.1]:33836] Telling the main thread
136s [[127.0.0.1]:33836] Let them tell us something...
136s [test_connect [127.0.0.1]:6503 1] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 1] - cert required
136s [test_connect [127.0.0.1]:6503 1] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 1] Opening an SSL connection
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[7]: Service [test] accepted connection from 127.0.0.1:41014'
136s [test_connect [127.0.0.1]:6503 1] Connected to the server: [127.0.0.1]:41014
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[7]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[7]: Service [test] connected remote server from 127.0.0.1:33848'
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:33848] New connection
136s [[127.0.0.1]:33848] Telling the main thread
136s [[127.0.0.1]:33848] Let them tell us something...
136s [test_connect [127.0.0.1]:6503 5] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 5] - cert required
136s [test_connect [127.0.0.1]:6503 5] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 5] Opening an SSL connection
136s [test_connect [127.0.0.1]:6503 9] Creating an SSL context
136s [test_connect [127.0.0.1]:6503 9] - cert required
136s [test_connect [127.0.0.1]:6503 9] - load_verify_locations()
136s [test_connect [127.0.0.1]:6503 9] Opening an SSL connection
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[8]: Service [test] accepted connection from 127.0.0.1:36600'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[9]: Service [test] accepted connection from 127.0.0.1:36616'
136s [test_connect [127.0.0.1]:6503 5] Connected to the server: [127.0.0.1]:36600
136s [test_connect [127.0.0.1]:6503 9] Connected to the server: [127.0.0.1]:36616
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:49670] New connection
136s [[127.0.0.1]:49670] Telling the main thread
136s [[127.0.0.1]:49670] Let them tell us something...
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[9]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[9]: Service [test] connected remote server from 127.0.0.1:49678'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[8]: s_connect: connected 127.0.0.1:6502'
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[8]: Service [test] connected remote server from 127.0.0.1:49670'
136s [listener] A client connected from somewhere
136s [[127.0.0.1]:49678] New connection
136s [[127.0.0.1]:49678] Telling the main thread
136s [[127.0.0.1]:49678] Let them tell us something...
136s [test_connect [127.0.0.1]:6503 0] Sending something...
136s [test_connect [127.0.0.1]:6503 0] Waiting for the main thread to figure it out
136s [[127.0.0.1]:33816] Telling the main thread about b'Hello 0\n'
136s [[127.0.0.1]:33816] Writing something back...
136s [[127.0.0.1]:33816] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:33816] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 0] The listener acknowledged [127.0.0.1]:33816
136s [test_connect [127.0.0.1]:6503 0] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 0] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 0] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 0] Waiting for the server to close the connection
136s [[127.0.0.1]:33816] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 0] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 0] Closing our writer socket, too
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
136s [test_connect [127.0.0.1]:6503 0] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 0] Done
136s [process_connections] Fetching task encr-0
136s [process_connections] Waiting for task encr-0
136s [process_connections] Done with task encr-0
136s [test_connect [127.0.0.1]:6503 2] Sending something...
136s [test_connect [127.0.0.1]:6503 2] Waiting for the main thread to figure it out
136s [[127.0.0.1]:33766] Telling the main thread about b'Hello 2\n'
136s [[127.0.0.1]:33766] Writing something back...
136s [[127.0.0.1]:33766] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:33766] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 2] The listener acknowledged [127.0.0.1]:33766
136s [test_connect [127.0.0.1]:6503 2] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 2] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 2] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 2] Waiting for the server to close the connection
136s [[127.0.0.1]:33766] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 2] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 2] Closing our writer socket, too
136s [test_connect [127.0.0.1]:6503 2] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 2] Done
136s [process_connections] Fetching task encr-2
136s [process_connections] Waiting for task encr-2
136s [process_connections] Done with task encr-2
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
136s [test_connect [127.0.0.1]:6503 3] Sending something...
136s [test_connect [127.0.0.1]:6503 3] Waiting for the main thread to figure it out
136s [[127.0.0.1]:33836] Telling the main thread about b'Hello 3\n'
136s [[127.0.0.1]:33836] Writing something back...
136s [[127.0.0.1]:33836] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:33836] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 3] The listener acknowledged [127.0.0.1]:33836
136s [test_connect [127.0.0.1]:6503 3] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 3] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 3] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 3] Waiting for the server to close the connection
136s [[127.0.0.1]:33836] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 3] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 3] Closing our writer socket, too
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
136s [test_connect [127.0.0.1]:6503 3] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 3] Done
136s [process_connections] Fetching task encr-3
136s [process_connections] Waiting for task encr-3
136s [process_connections] Done with task encr-3
136s [test_connect [127.0.0.1]:6503 5] Sending something...
136s [test_connect [127.0.0.1]:6503 5] Waiting for the main thread to figure it out
136s [[127.0.0.1]:49670] Telling the main thread about b'Hello 5\n'
136s [[127.0.0.1]:49670] Writing something back...
136s [[127.0.0.1]:49670] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:49670] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 5] The listener acknowledged [127.0.0.1]:49670
136s [test_connect [127.0.0.1]:6503 5] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 5] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 5] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 5] Waiting for the server to close the connection
136s [[127.0.0.1]:49670] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 5] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 5] Closing our writer socket, too
136s [test_connect [127.0.0.1]:6503 5] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 5] Done
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
136s [process_connections] Fetching task encr-5
136s [process_connections] Waiting for task encr-5
136s [process_connections] Done with task encr-5
136s [test_connect [127.0.0.1]:6503 1] Sending something...
136s [test_connect [127.0.0.1]:6503 1] Waiting for the main thread to figure it out
136s [[127.0.0.1]:33848] Telling the main thread about b'Hello 1\n'
136s [[127.0.0.1]:33848] Writing something back...
136s [[127.0.0.1]:33848] Telling the main thread about b'There!\n'
136s [[127.0.0.1]:33848] Closing down the writer socket
136s [test_connect [127.0.0.1]:6503 1] The listener acknowledged [127.0.0.1]:33848
136s [test_connect [127.0.0.1]:6503 1] Waiting for the server to receive it...
136s [test_connect [127.0.0.1]:6503 1] Waiting for the server to send something
136s [test_connect [127.0.0.1]:6503 1] Trying to receive the actual data
136s [test_connect [127.0.0.1]:6503 1] Waiting for the server to close the connection
136s [[127.0.0.1]:33848] Telling the main thread done
136s [test_connect [127.0.0.1]:6503 1] Waiting for an EOF on the reader socket
136s [test_connect [127.0.0.1]:6503 1] Closing our writer socket, too
136s [test_connect [127.0.0.1]:6503 1] Letting the main thread know we're done
136s [test_connect [127.0.0.1]:6503 1] Done
136s [process_connections] Fetching task encr-1
136s [process_connections] Waiting for task encr-1
136s [process_connections] Done with task encr-1
136s [stunnel_output] Read '2024.11.13 15:55:30 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
137s [test_connect [127.0.0.1]:6503 7] Sending something...
137s [test_connect [127.0.0.1]:6503 7] Waiting for the main thread to figure it out
137s [[127.0.0.1]:33792] Telling the main thread about b'Hello 7\n'
137s [[127.0.0.1]:33792] Writing something back...
137s [[127.0.0.1]:33792] Telling the main thread about b'There!\n'
137s [[127.0.0.1]:33792] Closing down the writer socket
137s [test_connect [127.0.0.1]:6503 7] The listener acknowledged [127.0.0.1]:33792
137s [test_connect [127.0.0.1]:6503 7] Waiting for the server to receive it...
137s [test_connect [127.0.0.1]:6503 7] Waiting for the server to send something
137s [test_connect [127.0.0.1]:6503 7] Trying to receive the actual data
137s [test_connect [127.0.0.1]:6503 7] Waiting for the server to close the connection
137s [[127.0.0.1]:33792] Telling the main thread done
137s [test_connect [127.0.0.1]:6503 7] Waiting for an EOF on the reader socket
137s [test_connect [127.0.0.1]:6503 7] Closing our writer socket, too
137s [test_connect [127.0.0.1]:6503 7] Letting the main thread know we're done
137s [test_connect [127.0.0.1]:6503 7] Done
137s [process_connections] Fetching task encr-7
137s [process_connections] Waiting for task encr-7
137s [process_connections] Done with task encr-7
137s [stunnel_output] Read '2024.11.13 15:55:31 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
137s [test_connect [127.0.0.1]:6503 6] Sending something...
137s [test_connect [127.0.0.1]:6503 6] Waiting for the main thread to figure it out
137s [[127.0.0.1]:33828] Telling the main thread about b'Hello 6\n'
137s [[127.0.0.1]:33828] Writing something back...
137s [[127.0.0.1]:33828] Telling the main thread about b'There!\n'
137s [[127.0.0.1]:33828] Closing down the writer socket
137s [test_connect [127.0.0.1]:6503 6] The listener acknowledged [127.0.0.1]:33828
137s [test_connect [127.0.0.1]:6503 6] Waiting for the server to receive it...
137s [test_connect [127.0.0.1]:6503 6] Waiting for the server to send something
137s [test_connect [127.0.0.1]:6503 6] Trying to receive the actual data
137s [test_connect [127.0.0.1]:6503 6] Waiting for the server to close the connection
137s [[127.0.0.1]:33828] Telling the main thread done
137s [test_connect [127.0.0.1]:6503 6] Waiting for an EOF on the reader socket
137s [test_connect [127.0.0.1]:6503 6] Closing our writer socket, too
137s [stunnel_output] Read '2024.11.13 15:55:31 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
137s [test_connect [127.0.0.1]:6503 6] Letting the main thread know we're done
137s [test_connect [127.0.0.1]:6503 6] Done
137s [process_connections] Fetching task encr-6
137s [process_connections] Waiting for task encr-6
137s [process_connections] Done with task encr-6
137s [test_connect [127.0.0.1]:6503 8] Sending something...
137s [test_connect [127.0.0.1]:6503 8] Waiting for the main thread to figure it out
137s [[127.0.0.1]:33804] Telling the main thread about b'Hello 8\n'
137s [[127.0.0.1]:33804] Writing something back...
137s [[127.0.0.1]:33804] Telling the main thread about b'There!\n'
137s [[127.0.0.1]:33804] Closing down the writer socket
137s [test_connect [127.0.0.1]:6503 8] The listener acknowledged [127.0.0.1]:33804
137s [test_connect [127.0.0.1]:6503 8] Waiting for the server to receive it...
137s [test_connect [127.0.0.1]:6503 8] Waiting for the server to send something
137s [test_connect [127.0.0.1]:6503 8] Trying to receive the actual data
137s [test_connect [127.0.0.1]:6503 8] Waiting for the server to close the connection
137s [[127.0.0.1]:33804] Telling the main thread done
137s [test_connect [127.0.0.1]:6503 8] Waiting for an EOF on the reader socket
137s [test_connect [127.0.0.1]:6503 8] Closing our writer socket, too
137s [test_connect [127.0.0.1]:6503 8] Letting the main thread know we're done
137s [test_connect [127.0.0.1]:6503 8] Done
137s [process_connections] Fetching task encr-8
137s [process_connections] Waiting for task encr-8
137s [process_connections] Done with task encr-8
137s [stunnel_output] Read '2024.11.13 15:55:31 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
137s [test_connect [127.0.0.1]:6503 9] Sending something...
137s [test_connect [127.0.0.1]:6503 9] Waiting for the main thread to figure it out
137s [[127.0.0.1]:49678] Telling the main thread about b'Hello 9\n'
137s [[127.0.0.1]:49678] Writing something back...
137s [[127.0.0.1]:49678] Telling the main thread about b'There!\n'
137s [[127.0.0.1]:49678] Closing down the writer socket
137s [test_connect [127.0.0.1]:6503 9] The listener acknowledged [127.0.0.1]:49678
137s [test_connect [127.0.0.1]:6503 9] Waiting for the server to receive it...
137s [test_connect [127.0.0.1]:6503 9] Waiting for the server to send something
137s [test_connect [127.0.0.1]:6503 9] Trying to receive the actual data
137s [test_connect [127.0.0.1]:6503 9] Waiting for the server to close the connection
137s [[127.0.0.1]:49678] Telling the main thread done
137s [test_connect [127.0.0.1]:6503 9] Waiting for an EOF on the reader socket
137s [test_connect [127.0.0.1]:6503 9] Closing our writer socket, too
137s [test_connect [127.0.0.1]:6503 9] Letting the main thread know we're done
137s [test_connect [127.0.0.1]:6503 9] Done
137s [process_connections] Fetching task encr-9
137s [process_connections] Waiting for task encr-9
137s [process_connections] Done with task encr-9
137s [main] Everything seems to be all right!
137s About to cancel 2 remaining task(s)
137s - listen
137s - output
137s Waiting for the tasks to hopefully finish
137s About to kill and wait for 1 child process(es)
137s - pid 2522
137s Waiting for the processes to exit...
137s Got processes' exit status: [-9]
137s 
137s 
137s ====== python3.13
137s 
137s Using /tmp/struntime.vw0m19f9 as a temporary directory
137s Trying to obtain the version of /usr/bin/stunnel4
137s Started `/usr/bin/stunnel4 -version` as process 2541
137s Got stunnel version 5.73
137s [main] Starting to do things
137s [main] Awaiting the 'listener started' event
137s [find_listening_port] Looking for a port to listen on
137s [find_listening_port] Trying localhost port 6502
137s [listener] Telling the main thread about [::1]:6502
137s [listener] Awaiting client connections...
137s [main] Apparently we are listening on [::1]:6502
137s [main] Testing cleartext connections
137s [test_connect [::1]:6502 0] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 1] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 2] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 3] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 4] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 5] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 6] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 7] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 8] Trying port 6502 encrypted False
137s [test_connect [::1]:6502 9] Trying port 6502 encrypted False
138s [test_connect [::1]:6502 7] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49090] New connection
138s [[::1]:49090] Telling the main thread
138s [[::1]:49090] Let them tell us something...
138s [test_connect [::1]:6502 7] Connected to the server: [::1]:49090
138s [test_connect [::1]:6502 6] Opening an unencrypted connection
138s [test_connect [::1]:6502 9] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49102] New connection
138s [[::1]:49102] Telling the main thread
138s [[::1]:49102] Let them tell us something...
138s [listener] A client connected from somewhere
138s [[::1]:49108] New connection
138s [[::1]:49108] Telling the main thread
138s [[::1]:49108] Let them tell us something...
138s [test_connect [::1]:6502 6] Connected to the server: [::1]:49102
138s [test_connect [::1]:6502 9] Connected to the server: [::1]:49108
138s [test_connect [::1]:6502 1] Opening an unencrypted connection
138s [test_connect [::1]:6502 2] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49114] New connection
138s [[::1]:49114] Telling the main thread
138s [[::1]:49114] Let them tell us something...
138s [listener] A client connected from somewhere
138s [[::1]:49124] New connection
138s [[::1]:49124] Telling the main thread
138s [[::1]:49124] Let them tell us something...
138s [test_connect [::1]:6502 1] Connected to the server: [::1]:49114
138s [test_connect [::1]:6502 2] Connected to the server: [::1]:49124
138s [test_connect [::1]:6502 8] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49134] New connection
138s [[::1]:49134] Telling the main thread
138s [[::1]:49134] Let them tell us something...
138s [test_connect [::1]:6502 8] Connected to the server: [::1]:49134
138s [test_connect [::1]:6502 4] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49142] New connection
138s [[::1]:49142] Telling the main thread
138s [[::1]:49142] Let them tell us something...
138s [test_connect [::1]:6502 4] Connected to the server: [::1]:49142
138s [test_connect [::1]:6502 3] Opening an unencrypted connection
138s [test_connect [::1]:6502 5] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49146] New connection
138s [[::1]:49146] Telling the main thread
138s [[::1]:49146] Let them tell us something...
138s [listener] A client connected from somewhere
138s [[::1]:49162] New connection
138s [[::1]:49162] Telling the main thread
138s [[::1]:49162] Let them tell us something...
138s [test_connect [::1]:6502 3] Connected to the server: [::1]:49146
138s [test_connect [::1]:6502 5] Connected to the server: [::1]:49162
138s [test_connect [::1]:6502 0] Opening an unencrypted connection
138s [listener] A client connected from somewhere
138s [[::1]:49164] New connection
138s [[::1]:49164] Telling the main thread
138s [[::1]:49164] Let them tell us something...
138s [test_connect [::1]:6502 0] Connected to the server: [::1]:49164
138s [test_connect [::1]:6502 7] Sending something...
138s [test_connect [::1]:6502 7] Waiting for the main thread to figure it out
138s [test_connect [::1]:6502 9] Sending something...
138s [test_connect [::1]:6502 9] Waiting for the main thread to figure it out
138s [[::1]:49090] Telling the main thread about b'Hello 7\n'
138s [[::1]:49090] Writing something back...
138s [[::1]:49090] Telling the main thread about b'There!\n'
138s [[::1]:49090] Closing down the writer socket
138s [[::1]:49108] Telling the main thread about b'Hello 9\n'
138s [[::1]:49108] Writing something back...
138s [[::1]:49108] Telling the main thread about b'There!\n'
138s [[::1]:49108] Closing down the writer socket
138s [test_connect [::1]:6502 7] The listener acknowledged [::1]:49090
138s [test_connect [::1]:6502 7] Waiting for the server to receive it...
138s [test_connect [::1]:6502 7] Waiting for the server to send something
138s [test_connect [::1]:6502 7] Trying to receive the actual data
138s [test_connect [::1]:6502 7] Waiting for the server to close the connection
138s [test_connect [::1]:6502 9] The listener acknowledged [::1]:49108
138s [test_connect [::1]:6502 9] Waiting for the server to receive it...
138s [test_connect [::1]:6502 9] Waiting for the server to send something
138s [test_connect [::1]:6502 9] Trying to receive the actual data
138s [test_connect [::1]:6502 9] Waiting for the server to close the connection
138s [[::1]:49090] Telling the main thread done
138s [[::1]:49108] Telling the main thread done
138s [test_connect [::1]:6502 7] Waiting for an EOF on the reader socket
138s [test_connect [::1]:6502 7] Closing our writer socket, too
138s [test_connect [::1]:6502 9] Waiting for an EOF on the reader socket
138s [test_connect [::1]:6502 9] Closing our writer socket, too
138s [test_connect [::1]:6502 7] Letting the main thread know we're done
138s [test_connect [::1]:6502 7] Done
138s [test_connect [::1]:6502 9] Letting the main thread know we're done
138s [test_connect [::1]:6502 9] Done
138s [process_connections] Fetching task plain-7
138s [process_connections] Waiting for task plain-7
138s [process_connections] Done with task plain-7
138s [process_connections] Fetching task plain-9
138s [process_connections] Waiting for task plain-9
138s [process_connections] Done with task plain-9
139s [test_connect [::1]:6502 8] Sending something...
139s [test_connect [::1]:6502 8] Waiting for the main thread to figure it out
139s [[::1]:49134] Telling the main thread about b'Hello 8\n'
139s [[::1]:49134] Writing something back...
139s [[::1]:49134] Telling the main thread about b'There!\n'
139s [[::1]:49134] Closing down the writer socket
139s [test_connect [::1]:6502 8] The listener acknowledged [::1]:49134
139s [test_connect [::1]:6502 8] Waiting for the server to receive it...
139s [test_connect [::1]:6502 8] Waiting for the server to send something
139s [test_connect [::1]:6502 8] Trying to receive the actual data
139s [test_connect [::1]:6502 8] Waiting for the server to close the connection
139s [[::1]:49134] Telling the main thread done
139s [test_connect [::1]:6502 6] Sending something...
139s [test_connect [::1]:6502 6] Waiting for the main thread to figure it out
139s [test_connect [::1]:6502 8] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 8] Closing our writer socket, too
139s [[::1]:49102] Telling the main thread about b'Hello 6\n'
139s [[::1]:49102] Writing something back...
139s [[::1]:49102] Telling the main thread about b'There!\n'
139s [[::1]:49102] Closing down the writer socket
139s [test_connect [::1]:6502 1] Sending something...
139s [test_connect [::1]:6502 1] Waiting for the main thread to figure it out
139s [test_connect [::1]:6502 2] Sending something...
139s [test_connect [::1]:6502 2] Waiting for the main thread to figure it out
139s [test_connect [::1]:6502 8] Letting the main thread know we're done
139s [test_connect [::1]:6502 8] Done
139s [test_connect [::1]:6502 6] The listener acknowledged [::1]:49102
139s [test_connect [::1]:6502 6] Waiting for the server to receive it...
139s [test_connect [::1]:6502 6] Waiting for the server to send something
139s [test_connect [::1]:6502 6] Trying to receive the actual data
139s [test_connect [::1]:6502 6] Waiting for the server to close the connection
139s [[::1]:49102] Telling the main thread done
139s [[::1]:49114] Telling the main thread about b'Hello 1\n'
139s [[::1]:49114] Writing something back...
139s [[::1]:49114] Telling the main thread about b'There!\n'
139s [[::1]:49114] Closing down the writer socket
139s [[::1]:49124] Telling the main thread about b'Hello 2\n'
139s [[::1]:49124] Writing something back...
139s [[::1]:49124] Telling the main thread about b'There!\n'
139s [[::1]:49124] Closing down the writer socket
139s [process_connections] Fetching task plain-8
139s [process_connections] Waiting for task plain-8
139s [process_connections] Done with task plain-8
139s [test_connect [::1]:6502 6] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 6] Closing our writer socket, too
139s [test_connect [::1]:6502 1] The listener acknowledged [::1]:49114
139s [test_connect [::1]:6502 1] Waiting for the server to receive it...
139s [test_connect [::1]:6502 1] Waiting for the server to send something
139s [test_connect [::1]:6502 1] Trying to receive the actual data
139s [test_connect [::1]:6502 1] Waiting for the server to close the connection
139s [test_connect [::1]:6502 2] The listener acknowledged [::1]:49124
139s [test_connect [::1]:6502 2] Waiting for the server to receive it...
139s [test_connect [::1]:6502 2] Waiting for the server to send something
139s [test_connect [::1]:6502 2] Trying to receive the actual data
139s [test_connect [::1]:6502 2] Waiting for the server to close the connection
139s [[::1]:49114] Telling the main thread done
139s [[::1]:49124] Telling the main thread done
139s [test_connect [::1]:6502 6] Letting the main thread know we're done
139s [test_connect [::1]:6502 6] Done
139s [test_connect [::1]:6502 1] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 1] Closing our writer socket, too
139s [test_connect [::1]:6502 2] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 2] Closing our writer socket, too
139s [process_connections] Fetching task plain-6
139s [process_connections] Waiting for task plain-6
139s [process_connections] Done with task plain-6
139s [test_connect [::1]:6502 1] Letting the main thread know we're done
139s [test_connect [::1]:6502 1] Done
139s [test_connect [::1]:6502 2] Letting the main thread know we're done
139s [test_connect [::1]:6502 2] Done
139s [process_connections] Fetching task plain-1
139s [process_connections] Waiting for task plain-1
139s [process_connections] Done with task plain-1
139s [process_connections] Fetching task plain-2
139s [process_connections] Waiting for task plain-2
139s [process_connections] Done with task plain-2
139s [test_connect [::1]:6502 3] Sending something...
139s [test_connect [::1]:6502 3] Waiting for the main thread to figure it out
139s [[::1]:49146] Telling the main thread about b'Hello 3\n'
139s [[::1]:49146] Writing something back...
139s [[::1]:49146] Telling the main thread about b'There!\n'
139s [[::1]:49146] Closing down the writer socket
139s [test_connect [::1]:6502 3] The listener acknowledged [::1]:49146
139s [test_connect [::1]:6502 3] Waiting for the server to receive it...
139s [test_connect [::1]:6502 3] Waiting for the server to send something
139s [test_connect [::1]:6502 3] Trying to receive the actual data
139s [test_connect [::1]:6502 3] Waiting for the server to close the connection
139s [[::1]:49146] Telling the main thread done
139s [test_connect [::1]:6502 3] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 3] Closing our writer socket, too
139s [test_connect [::1]:6502 3] Letting the main thread know we're done
139s [test_connect [::1]:6502 3] Done
139s [process_connections] Fetching task plain-3
139s [process_connections] Waiting for task plain-3
139s [process_connections] Done with task plain-3
139s [test_connect [::1]:6502 4] Sending something...
139s [test_connect [::1]:6502 4] Waiting for the main thread to figure it out
139s [test_connect [::1]:6502 0] Sending something...
139s [test_connect [::1]:6502 0] Waiting for the main thread to figure it out
139s [[::1]:49142] Telling the main thread about b'Hello 4\n'
139s [[::1]:49142] Writing something back...
139s [[::1]:49142] Telling the main thread about b'There!\n'
139s [[::1]:49142] Closing down the writer socket
139s [[::1]:49164] Telling the main thread about b'Hello 0\n'
139s [[::1]:49164] Writing something back...
139s [[::1]:49164] Telling the main thread about b'There!\n'
139s [[::1]:49164] Closing down the writer socket
139s [test_connect [::1]:6502 5] Sending something...
139s [test_connect [::1]:6502 5] Waiting for the main thread to figure it out
139s [test_connect [::1]:6502 4] The listener acknowledged [::1]:49142
139s [test_connect [::1]:6502 4] Waiting for the server to receive it...
139s [test_connect [::1]:6502 4] Waiting for the server to send something
139s [test_connect [::1]:6502 4] Trying to receive the actual data
139s [test_connect [::1]:6502 4] Waiting for the server to close the connection
139s [test_connect [::1]:6502 0] The listener acknowledged [::1]:49164
139s [test_connect [::1]:6502 0] Waiting for the server to receive it...
139s [test_connect [::1]:6502 0] Waiting for the server to send something
139s [test_connect [::1]:6502 0] Trying to receive the actual data
139s [test_connect [::1]:6502 0] Waiting for the server to close the connection
139s [[::1]:49142] Telling the main thread done
139s [[::1]:49164] Telling the main thread done
139s [[::1]:49162] Telling the main thread about b'Hello 5\n'
139s [[::1]:49162] Writing something back...
139s [[::1]:49162] Telling the main thread about b'There!\n'
139s [[::1]:49162] Closing down the writer socket
139s [test_connect [::1]:6502 4] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 4] Closing our writer socket, too
139s [test_connect [::1]:6502 0] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 0] Closing our writer socket, too
139s [test_connect [::1]:6502 5] The listener acknowledged [::1]:49162
139s [test_connect [::1]:6502 5] Waiting for the server to receive it...
139s [test_connect [::1]:6502 5] Waiting for the server to send something
139s [test_connect [::1]:6502 5] Trying to receive the actual data
139s [test_connect [::1]:6502 5] Waiting for the server to close the connection
139s [[::1]:49162] Telling the main thread done
139s [test_connect [::1]:6502 4] Letting the main thread know we're done
139s [test_connect [::1]:6502 4] Done
139s [test_connect [::1]:6502 0] Letting the main thread know we're done
139s [test_connect [::1]:6502 0] Done
139s [test_connect [::1]:6502 5] Waiting for an EOF on the reader socket
139s [test_connect [::1]:6502 5] Closing our writer socket, too
139s [process_connections] Fetching task plain-4
139s [process_connections] Waiting for task plain-4
139s [process_connections] Done with task plain-4
139s [process_connections] Fetching task plain-0
139s [process_connections] Waiting for task plain-0
139s [process_connections] Done with task plain-0
139s [test_connect [::1]:6502 5] Letting the main thread know we're done
139s [test_connect [::1]:6502 5] Done
139s [process_connections] Fetching task plain-5
139s [process_connections] Waiting for task plain-5
139s [process_connections] Done with task plain-5
139s [main] Picking a listen address:port for stunnel
139s [find_listening_port] Looking for a port to listen on
139s [find_listening_port] Trying ::1 port 6503
139s [find_listening_port] got 6503, shutting down the listener
139s [main] Will put stunnel at ::1:6503
139s [main] Preparing the stunnel config file
139s [main] Using config file /tmp/struntime.vw0m19f9/stunnel.conf
139s [main] 
139s [main] pid = /tmp/struntime.vw0m19f9/stunnel.pid
139s [main] foreground = yes
139s [main] 
139s [main] cert = /tmp/autopkgtest.FhbnhQ/build.W5m/src/debian/tests/certs/certificate.pem
139s [main] key = /tmp/struntime.vw0m19f9/key.pem
139s [main] 
139s [main] [test]
139s [main] accept = ::1:6503
139s [main] connect = ::1:6502
139s [main] End of the config file
139s [main] Launching the stunnel server
139s [main] - got pid 2545
139s [main] Launching the stunnel output pipe thread
139s [main] Waiting for stunnel to start up
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: stunnel 5.73 on powerpc64le-unknown-linux-gnu platform'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: Compiled/running with OpenSSL 3.3.1 4 Jun 2024'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: Reading configuration from file /tmp/struntime.vw0m19f9/stunnel.conf'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: UTF-8 byte order mark not detected'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: FIPS mode disabled'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[ui]: Configuration successful'
139s [stunnel_output] The main thread ought to know
139s [main] Testing the encrypted connections
139s [test_connect [::1]:6503 0] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 1] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 2] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 3] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 4] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 5] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 6] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 7] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 8] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 9] Trying port 6503 encrypted True
139s [test_connect [::1]:6503 0] Creating an SSL context
139s [test_connect [::1]:6503 0] - cert required
139s [test_connect [::1]:6503 0] - load_verify_locations()
139s [test_connect [::1]:6503 0] Opening an SSL connection
139s [test_connect [::1]:6503 1] Creating an SSL context
139s [test_connect [::1]:6503 1] - cert required
139s [test_connect [::1]:6503 1] - load_verify_locations()
139s [test_connect [::1]:6503 1] Opening an SSL connection
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[0]: Service [test] accepted connection from ::1:53816'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[1]: Service [test] accepted connection from ::1:53832'
139s [test_connect [::1]:6503 1] Connected to the server: [::1]:53832
139s [test_connect [::1]:6503 0] Connected to the server: [::1]:53816
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[1]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[1]: Service [test] connected remote server from ::1:49172'
139s [listener] A client connected from somewhere
139s [[::1]:49172] New connection
139s [[::1]:49172] Telling the main thread
139s [[::1]:49172] Let them tell us something...
139s [listener] A client connected from somewhere
139s [[::1]:49180] New connection
139s [[::1]:49180] Telling the main thread
139s [[::1]:49180] Let them tell us something...
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[0]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[0]: Service [test] connected remote server from ::1:49180'
139s [test_connect [::1]:6503 9] Creating an SSL context
139s [test_connect [::1]:6503 9] - cert required
139s [test_connect [::1]:6503 9] - load_verify_locations()
139s [test_connect [::1]:6503 9] Opening an SSL connection
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[2]: Service [test] accepted connection from ::1:53834'
139s [test_connect [::1]:6503 9] Connected to the server: [::1]:53834
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[2]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[2]: Service [test] connected remote server from ::1:49188'
139s [listener] A client connected from somewhere
139s [[::1]:49188] New connection
139s [[::1]:49188] Telling the main thread
139s [[::1]:49188] Let them tell us something...
139s [test_connect [::1]:6503 2] Creating an SSL context
139s [test_connect [::1]:6503 2] - cert required
139s [test_connect [::1]:6503 2] - load_verify_locations()
139s [test_connect [::1]:6503 2] Opening an SSL connection
139s [test_connect [::1]:6503 8] Creating an SSL context
139s [test_connect [::1]:6503 8] - cert required
139s [test_connect [::1]:6503 8] - load_verify_locations()
139s [test_connect [::1]:6503 8] Opening an SSL connection
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[3]: Service [test] accepted connection from ::1:53848'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[4]: Service [test] accepted connection from ::1:53860'
139s [test_connect [::1]:6503 2] Connected to the server: [::1]:53848
139s [test_connect [::1]:6503 8] Connected to the server: [::1]:53860
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[3]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[3]: Service [test] connected remote server from ::1:49190'
139s [listener] A client connected from somewhere
139s [[::1]:49190] New connection
139s [[::1]:49190] Telling the main thread
139s [[::1]:49190] Let them tell us something...
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[4]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[4]: Service [test] connected remote server from ::1:49204'
139s [listener] A client connected from somewhere
139s [[::1]:49204] New connection
139s [[::1]:49204] Telling the main thread
139s [[::1]:49204] Let them tell us something...
139s [test_connect [::1]:6503 5] Creating an SSL context
139s [test_connect [::1]:6503 5] - cert required
139s [test_connect [::1]:6503 5] - load_verify_locations()
139s [test_connect [::1]:6503 5] Opening an SSL connection
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[5]: Service [test] accepted connection from ::1:53866'
139s [test_connect [::1]:6503 5] Connected to the server: [::1]:53866
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[5]: s_connect: connected ::1:6502'
139s [stunnel_output] Read '2024.11.13 15:55:33 LOG5[5]: Service [test] connected remote server from ::1:49218'
139s [listener] A client connected from somewhere
139s [[::1]:49218] New connection
139s [[::1]:49218] Telling the main thread
139s [[::1]:49218] Let them tell us something...
140s [test_connect [::1]:6503 7] Creating an SSL context
140s [test_connect [::1]:6503 7] - cert required
140s [test_connect [::1]:6503 7] - load_verify_locations()
140s [test_connect [::1]:6503 7] Opening an SSL connection
140s [test_connect [::1]:6503 7] Connected to the server: [::1]:53880
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[6]: Service [test] accepted connection from ::1:53880'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[6]: s_connect: connected ::1:6502'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[6]: Service [test] connected remote server from ::1:49222'
140s [listener] A client connected from somewhere
140s [[::1]:49222] New connection
140s [[::1]:49222] Telling the main thread
140s [[::1]:49222] Let them tell us something...
140s [test_connect [::1]:6503 4] Creating an SSL context
140s [test_connect [::1]:6503 4] - cert required
140s [test_connect [::1]:6503 4] - load_verify_locations()
140s [test_connect [::1]:6503 4] Opening an SSL connection
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[7]: Service [test] accepted connection from ::1:53884'
140s [test_connect [::1]:6503 4] Connected to the server: [::1]:53884
140s [test_connect [::1]:6503 2] Sending something...
140s [test_connect [::1]:6503 2] Waiting for the main thread to figure it out
140s [test_connect [::1]:6503 9] Sending something...
140s [test_connect [::1]:6503 9] Waiting for the main thread to figure it out
140s [[::1]:49190] Telling the main thread about b'Hello 2\n'
140s [[::1]:49190] Writing something back...
140s [[::1]:49190] Telling the main thread about b'There!\n'
140s [[::1]:49190] Closing down the writer socket
140s [test_connect [::1]:6503 2] The listener acknowledged [::1]:49190
140s [test_connect [::1]:6503 2] Waiting for the server to receive it...
140s [test_connect [::1]:6503 2] Waiting for the server to send something
140s [test_connect [::1]:6503 2] Trying to receive the actual data
140s [test_connect [::1]:6503 2] Waiting for the server to close the connection
140s [[::1]:49190] Telling the main thread done
140s [[::1]:49188] Telling the main thread about b'Hello 9\n'
140s [[::1]:49188] Writing something back...
140s [[::1]:49188] Telling the main thread about b'There!\n'
140s [[::1]:49188] Closing down the writer socket
140s [test_connect [::1]:6503 2] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 2] Closing our writer socket, too
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[7]: s_connect: connected ::1:6502'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[7]: Service [test] connected remote server from ::1:49234'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 2] Letting the main thread know we're done
140s [test_connect [::1]:6503 2] Done
140s [listener] A client connected from somewhere
140s [[::1]:49234] New connection
140s [[::1]:49234] Telling the main thread
140s [[::1]:49234] Let them tell us something...
140s [test_connect [::1]:6503 9] The listener acknowledged [::1]:49188
140s [test_connect [::1]:6503 9] Waiting for the server to receive it...
140s [test_connect [::1]:6503 9] Waiting for the server to send something
140s [test_connect [::1]:6503 9] Trying to receive the actual data
140s [[::1]:49188] Telling the main thread done
140s [process_connections] Fetching task encr-2
140s [process_connections] Waiting for task encr-2
140s [process_connections] Done with task encr-2
140s [test_connect [::1]:6503 9] Waiting for the server to close the connection
140s [test_connect [::1]:6503 9] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 9] Closing our writer socket, too
140s [test_connect [::1]:6503 9] Letting the main thread know we're done
140s [test_connect [::1]:6503 9] Done
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [process_connections] Fetching task encr-9
140s [process_connections] Waiting for task encr-9
140s [process_connections] Done with task encr-9
140s [test_connect [::1]:6503 1] Sending something...
140s [test_connect [::1]:6503 1] Waiting for the main thread to figure it out
140s [[::1]:49172] Telling the main thread about b'Hello 1\n'
140s [[::1]:49172] Writing something back...
140s [[::1]:49172] Telling the main thread about b'There!\n'
140s [[::1]:49172] Closing down the writer socket
140s [test_connect [::1]:6503 1] The listener acknowledged [::1]:49172
140s [test_connect [::1]:6503 1] Waiting for the server to receive it...
140s [test_connect [::1]:6503 1] Waiting for the server to send something
140s [test_connect [::1]:6503 1] Trying to receive the actual data
140s [test_connect [::1]:6503 1] Waiting for the server to close the connection
140s [[::1]:49172] Telling the main thread done
140s [test_connect [::1]:6503 1] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 1] Closing our writer socket, too
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 1] Letting the main thread know we're done
140s [test_connect [::1]:6503 1] Done
140s [process_connections] Fetching task encr-1
140s [process_connections] Waiting for task encr-1
140s [process_connections] Done with task encr-1
140s [test_connect [::1]:6503 3] Creating an SSL context
140s [test_connect [::1]:6503 3] - cert required
140s [test_connect [::1]:6503 3] - load_verify_locations()
140s [test_connect [::1]:6503 3] Opening an SSL connection
140s [test_connect [::1]:6503 6] Creating an SSL context
140s [test_connect [::1]:6503 6] - cert required
140s [test_connect [::1]:6503 6] - load_verify_locations()
140s [test_connect [::1]:6503 6] Opening an SSL connection
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[8]: Service [test] accepted connection from ::1:53900'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[9]: Service [test] accepted connection from ::1:53910'
140s [test_connect [::1]:6503 6] Connected to the server: [::1]:53910
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[9]: s_connect: connected ::1:6502'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[9]: Service [test] connected remote server from ::1:49244'
140s [listener] A client connected from somewhere
140s [[::1]:49244] New connection
140s [[::1]:49244] Telling the main thread
140s [[::1]:49244] Let them tell us something...
140s [test_connect [::1]:6503 3] Connected to the server: [::1]:53900
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[8]: s_connect: connected ::1:6502'
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[8]: Service [test] connected remote server from ::1:49260'
140s [listener] A client connected from somewhere
140s [[::1]:49260] New connection
140s [[::1]:49260] Telling the main thread
140s [[::1]:49260] Let them tell us something...
140s [test_connect [::1]:6503 0] Sending something...
140s [test_connect [::1]:6503 0] Waiting for the main thread to figure it out
140s [[::1]:49180] Telling the main thread about b'Hello 0\n'
140s [[::1]:49180] Writing something back...
140s [[::1]:49180] Telling the main thread about b'There!\n'
140s [[::1]:49180] Closing down the writer socket
140s [test_connect [::1]:6503 0] The listener acknowledged [::1]:49180
140s [test_connect [::1]:6503 0] Waiting for the server to receive it...
140s [test_connect [::1]:6503 0] Waiting for the server to send something
140s [test_connect [::1]:6503 0] Trying to receive the actual data
140s [[::1]:49180] Telling the main thread done
140s [test_connect [::1]:6503 0] Waiting for the server to close the connection
140s [test_connect [::1]:6503 0] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 0] Closing our writer socket, too
140s [test_connect [::1]:6503 0] Letting the main thread know we're done
140s [test_connect [::1]:6503 0] Done
140s [process_connections] Fetching task encr-0
140s [process_connections] Waiting for task encr-0
140s [process_connections] Done with task encr-0
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 4] Sending something...
140s [test_connect [::1]:6503 4] Waiting for the main thread to figure it out
140s [[::1]:49234] Telling the main thread about b'Hello 4\n'
140s [[::1]:49234] Writing something back...
140s [[::1]:49234] Telling the main thread about b'There!\n'
140s [[::1]:49234] Closing down the writer socket
140s [test_connect [::1]:6503 5] Sending something...
140s [test_connect [::1]:6503 5] Waiting for the main thread to figure it out
140s [test_connect [::1]:6503 4] The listener acknowledged [::1]:49234
140s [test_connect [::1]:6503 4] Waiting for the server to receive it...
140s [test_connect [::1]:6503 4] Waiting for the server to send something
140s [test_connect [::1]:6503 4] Trying to receive the actual data
140s [test_connect [::1]:6503 4] Waiting for the server to close the connection
140s [[::1]:49234] Telling the main thread done
140s [[::1]:49218] Telling the main thread about b'Hello 5\n'
140s [[::1]:49218] Writing something back...
140s [[::1]:49218] Telling the main thread about b'There!\n'
140s [[::1]:49218] Closing down the writer socket
140s [test_connect [::1]:6503 4] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 4] Closing our writer socket, too
140s [test_connect [::1]:6503 4] Letting the main thread know we're done
140s [test_connect [::1]:6503 4] Done
140s [test_connect [::1]:6503 5] The listener acknowledged [::1]:49218
140s [test_connect [::1]:6503 5] Waiting for the server to receive it...
140s [test_connect [::1]:6503 5] Waiting for the server to send something
140s [test_connect [::1]:6503 5] Trying to receive the actual data
140s [test_connect [::1]:6503 5] Waiting for the server to close the connection
140s [[::1]:49218] Telling the main thread done
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [process_connections] Fetching task encr-4
140s [process_connections] Waiting for task encr-4
140s [process_connections] Done with task encr-4
140s [test_connect [::1]:6503 8] Sending something...
140s [test_connect [::1]:6503 8] Waiting for the main thread to figure it out
140s [test_connect [::1]:6503 5] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 5] Closing our writer socket, too
140s [[::1]:49204] Telling the main thread about b'Hello 8\n'
140s [[::1]:49204] Writing something back...
140s [[::1]:49204] Telling the main thread about b'There!\n'
140s [[::1]:49204] Closing down the writer socket
140s [test_connect [::1]:6503 8] The listener acknowledged [::1]:49204
140s [test_connect [::1]:6503 8] Waiting for the server to receive it...
140s [test_connect [::1]:6503 8] Waiting for the server to send something
140s [test_connect [::1]:6503 8] Trying to receive the actual data
140s [test_connect [::1]:6503 8] Waiting for the server to close the connection
140s [[::1]:49204] Telling the main thread done
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 5] Letting the main thread know we're done
140s [test_connect [::1]:6503 5] Done
140s [process_connections] Fetching task encr-5
140s [process_connections] Waiting for task encr-5
140s [process_connections] Done with task encr-5
140s [test_connect [::1]:6503 8] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 8] Closing our writer socket, too
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 8] Letting the main thread know we're done
140s [test_connect [::1]:6503 8] Done
140s [process_connections] Fetching task encr-8
140s [process_connections] Waiting for task encr-8
140s [process_connections] Done with task encr-8
140s [test_connect [::1]:6503 6] Sending something...
140s [test_connect [::1]:6503 6] Waiting for the main thread to figure it out
140s [[::1]:49244] Telling the main thread about b'Hello 6\n'
140s [[::1]:49244] Writing something back...
140s [[::1]:49244] Telling the main thread about b'There!\n'
140s [[::1]:49244] Closing down the writer socket
140s [test_connect [::1]:6503 6] The listener acknowledged [::1]:49244
140s [test_connect [::1]:6503 6] Waiting for the server to receive it...
140s [test_connect [::1]:6503 6] Waiting for the server to send something
140s [test_connect [::1]:6503 6] Trying to receive the actual data
140s [test_connect [::1]:6503 6] Waiting for the server to close the connection
140s [[::1]:49244] Telling the main thread done
140s [test_connect [::1]:6503 6] Waiting for an EOF on the reader socket
140s [test_connect [::1]:6503 6] Closing our writer socket, too
140s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
140s [test_connect [::1]:6503 6] Letting the main thread know we're done
140s [test_connect [::1]:6503 6] Done
140s [process_connections] Fetching task encr-6
140s [process_connections] Waiting for task encr-6
140s [process_connections] Done with task encr-6
141s [test_connect [::1]:6503 7] Sending something...
141s [test_connect [::1]:6503 7] Waiting for the main thread to figure it out
141s [[::1]:49222] Telling the main thread about b'Hello 7\n'
141s [[::1]:49222] Writing something back...
141s [[::1]:49222] Telling the main thread about b'There!\n'
141s [[::1]:49222] Closing down the writer socket
141s [test_connect [::1]:6503 7] The listener acknowledged [::1]:49222
141s [test_connect [::1]:6503 7] Waiting for the server to receive it...
141s [test_connect [::1]:6503 7] Waiting for the server to send something
141s [test_connect [::1]:6503 7] Trying to receive the actual data
141s [test_connect [::1]:6503 7] Waiting for the server to close the connection
141s [[::1]:49222] Telling the main thread done
141s [test_connect [::1]:6503 7] Waiting for an EOF on the reader socket
141s [test_connect [::1]:6503 7] Closing our writer socket, too
141s [stunnel_output] Read '2024.11.13 15:55:34 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket'
141s [test_connect [::1]:6503 7] Letting the main thread know we're done
141s [test_connect [::1]:6503 7] Done
141s [process_connections] Fetching task encr-7
141s [process_connections] Waiting for task encr-7
141s [process_connections] Done with task encr-7
141s [test_connect [::1]:6503 3] Sending something...
141s [test_connect [::1]:6503 3] Waiting for the main thread to figure it out
141s [[::1]:49260] Telling the main thread about b'Hello 3\n'
141s [[::1]:49260] Writing something back...
141s [[::1]:49260] Telling the main thread about b'There!\n'
141s [[::1]:49260] Closing down the writer socket
141s [test_connect [::1]:6503 3] The listener acknowledged [::1]:49260
141s [test_connect [::1]:6503 3] Waiting for the server to receive it...
141s [test_connect [::1]:6503 3] Waiting for the server to send something
141s [test_connect [::1]:6503 3] Trying to receive the actual data
141s [[::1]:49260] Telling the main thread done
141s [test_connect [::1]:6503 3] Waiting for the server to close the connection
141s [test_connect [::1]:6503 3] Waiting for an EOF on the reader socket
141s [test_connect [::1]:6503 3] Closing our writer socket, too
141s [test_connect [::1]:6503 3] Letting the main thread know we're done
141s [test_connect [::1]:6503 3] Done
141s [process_connections] Fetching task encr-3
141s [process_connections] Waiting for task encr-3
141s [process_connections] Done with task encr-3
141s [main] Everything seems to be all right!
141s About to cancel 2 remaining task(s)
141s - listen
141s - output
141s Waiting for the tasks to hopefully finish
141s About to kill and wait for 1 child process(es)
141s - pid 2545
141s Waiting for the processes to exit...
141s Got processes' exit status: [-9]
141s autopkgtest [15:55:35]: test debian-python: -----------------------]
142s debian-python        PASS
142s autopkgtest [15:55:36]: test debian-python:  - - - - - - - - - - results - - - - - - - - - -
142s autopkgtest [15:55:36]: test upstream: preparing testbed
251s autopkgtest [15:57:25]: testbed dpkg architecture: ppc64el
251s autopkgtest [15:57:25]: testbed apt version: 2.9.8
251s autopkgtest [15:57:25]: @@@@@@@@@@@@@@@@@@@@ test bed setup
252s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB]
253s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.2 kB]
253s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [104 kB]
253s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [950 kB]
253s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B]
253s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [111 kB]
253s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [660 kB]
253s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [20.8 kB]
254s Fetched 1944 kB in 1s (1693 kB/s)
254s Reading package lists...
257s Reading package lists...
257s Building dependency tree...
257s Reading state information...
258s Calculating upgrade...
258s The following NEW packages will be installed:
258s   python3.13-gdbm
258s The following packages will be upgraded:
258s   libgnutls30t64 libjson-glib-1.0-0 libjson-glib-1.0-common libpython3-stdlib
258s   libutempter0 python3 python3-gdbm python3-minimal
258s 8 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
258s Need to get 1265 kB of archives.
258s After this operation, 141 kB of additional disk space will be used.
258s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-minimal ppc64el 3.12.7-1 [27.4 kB]
258s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3 ppc64el 3.12.7-1 [24.0 kB]
258s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpython3-stdlib ppc64el 3.12.7-1 [10.0 kB]
258s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgnutls30t64 ppc64el 3.8.8-2ubuntu1 [1072 kB]
259s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.13-gdbm ppc64el 3.13.0-2 [31.5 kB]
259s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-gdbm ppc64el 3.12.7-1 [8640 B]
259s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-common all 1.10.0+ds-3 [5586 B]
259s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-0 ppc64el 1.10.0+ds-3 [76.0 kB]
259s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libutempter0 ppc64el 1.2.1-4 [9850 B]
259s Fetched 1265 kB in 1s (2121 kB/s)
260s (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 ... 73767 files and directories currently installed.)
260s Preparing to unpack .../python3-minimal_3.12.7-1_ppc64el.deb ...
260s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ...
260s Setting up python3-minimal (3.12.7-1) ...
260s (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 ... 73767 files and directories currently installed.)
260s Preparing to unpack .../python3_3.12.7-1_ppc64el.deb ...
260s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ...
260s Preparing to unpack .../libpython3-stdlib_3.12.7-1_ppc64el.deb ...
260s Unpacking libpython3-stdlib:ppc64el (3.12.7-1) over (3.12.6-0ubuntu1) ...
260s Preparing to unpack .../libgnutls30t64_3.8.8-2ubuntu1_ppc64el.deb ...
260s Unpacking libgnutls30t64:ppc64el (3.8.8-2ubuntu1) over (3.8.6-2ubuntu1) ...
260s Setting up libgnutls30t64:ppc64el (3.8.8-2ubuntu1) ...
260s Selecting previously unselected package python3.13-gdbm.
260s (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 ... 73767 files and directories currently installed.)
260s Preparing to unpack .../python3.13-gdbm_3.13.0-2_ppc64el.deb ...
260s Unpacking python3.13-gdbm (3.13.0-2) ...
260s Preparing to unpack .../python3-gdbm_3.12.7-1_ppc64el.deb ...
260s Unpacking python3-gdbm:ppc64el (3.12.7-1) over (3.12.6-1ubuntu1) ...
260s Preparing to unpack .../libjson-glib-1.0-common_1.10.0+ds-3_all.deb ...
260s Unpacking libjson-glib-1.0-common (1.10.0+ds-3) over (1.10.0+ds-2) ...
260s Preparing to unpack .../libjson-glib-1.0-0_1.10.0+ds-3_ppc64el.deb ...
260s Unpacking libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) over (1.10.0+ds-2) ...
260s Preparing to unpack .../libutempter0_1.2.1-4_ppc64el.deb ...
260s Unpacking libutempter0:ppc64el (1.2.1-4) over (1.2.1-3build1) ...
261s Setting up libutempter0:ppc64el (1.2.1-4) ...
261s Setting up libjson-glib-1.0-common (1.10.0+ds-3) ...
261s Setting up python3.13-gdbm (3.13.0-2) ...
261s Setting up libpython3-stdlib:ppc64el (3.12.7-1) ...
261s Setting up python3 (3.12.7-1) ...
261s Setting up libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) ...
261s Setting up python3-gdbm:ppc64el (3.12.7-1) ...
261s Processing triggers for man-db (2.12.1-3) ...
262s Processing triggers for libc-bin (2.40-1ubuntu3) ...
262s Reading package lists...
263s Building dependency tree...
263s Reading state information...
263s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
264s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease
264s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
264s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
264s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
266s Reading package lists...
266s Reading package lists...
266s Building dependency tree...
266s Reading state information...
266s Calculating upgrade...
267s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
267s Reading package lists...
267s Building dependency tree...
267s Reading state information...
268s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
272s Reading package lists...
272s Building dependency tree...
272s Reading state information...
272s Starting pkgProblemResolver with broken count: 0
272s Starting 2 pkgProblemResolver with broken count: 0
272s Done
273s The following additional packages will be installed:
273s   net-tools stunnel4
273s Suggested packages:
273s   logcheck-database
273s The following NEW packages will be installed:
273s   autopkgtest-satdep net-tools stunnel4
273s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded.
273s Need to get 462 kB/463 kB of archives.
273s After this operation, 2075 kB of additional disk space will be used.
273s Get:1 /tmp/autopkgtest.FhbnhQ/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [748 B]
273s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el stunnel4 ppc64el 3:5.73-1 [237 kB]
274s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el net-tools ppc64el 2.10-1.1ubuntu1 [225 kB]
274s Fetched 462 kB in 1s (864 kB/s)
274s Selecting previously unselected package stunnel4.
274s (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 ... 73774 files and directories currently installed.)
274s Preparing to unpack .../stunnel4_3%3a5.73-1_ppc64el.deb ...
274s Unpacking stunnel4 (3:5.73-1) ...
274s Selecting previously unselected package net-tools.
274s Preparing to unpack .../net-tools_2.10-1.1ubuntu1_ppc64el.deb ...
274s Unpacking net-tools (2.10-1.1ubuntu1) ...
274s Selecting previously unselected package autopkgtest-satdep.
274s Preparing to unpack .../2-autopkgtest-satdep.deb ...
274s Unpacking autopkgtest-satdep (0) ...
274s Setting up stunnel4 (3:5.73-1) ...
274s Creating group 'stunnel4' with GID 988.

274s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988.

275s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'.

276s Setting up net-tools (2.10-1.1ubuntu1) ...
276s Setting up autopkgtest-satdep (0) ...
276s Processing triggers for man-db (2.12.1-3) ...
280s (Reading database ... 73866 files and directories currently installed.)
280s Removing autopkgtest-satdep (0) ...
282s autopkgtest [15:57:56]: test upstream: debian/tests/upstream
282s autopkgtest [15:57:56]: test upstream: [-----------------------
284s keys & certificates successfully generated
284s ./maketestcert.sh finished
285s 011. Test "cert" option
285s ...................................................................... succeeded
285s 012. Test no "cert" option
285s ...................................................................... succeeded
285s 021. Test "requireCert" option
285s ...................................................................... succeeded
285s 022. Failure test "requireCert" option
285s ...................................................................... succeeded
285s 031. Test "verifyPeer" option
285s ...................................................................... succeeded
285s 032. Failure test "verifyPeer" option
285s ...................................................................... succeeded
285s 041. Test "verifyChain" option
285s ...................................................................... succeeded
285s 042. Failure test "verifyChain" option
285s ...................................................................... succeeded
285s 051. CRL file with valid certificate
285s ...................................................................... succeeded
285s 052. CRL file with revoked certificate
285s ...................................................................... succeeded
285s 061. Test "PSKsecrets" option
285s ...................................................................... succeeded
285s 062. Failure test "PSKsecrets" option
285s ...................................................................... succeeded
285s 071. Test PKCS#12 certificate
285s ...................................................................... succeeded
285s 081. Test IPv6 support
285s ...................................................................... succeeded
285s 091. Binding server
285s ...................................................................... succeeded
285s 101. Test FIPS mode
286s ...................................................................... skipped
286s 111. Failure FIPS mode with unavailable cipher
286s ...................................................................... skipped
286s 112. Failure FIPS mode with unavailable ciphersuite
286s ...................................................................... skipped
286s 113. Failure FIPS mode with unavailable elliptic curve
286s ...................................................................... skipped
286s 121. Test "sni" option
286s ...................................................................... succeeded
286s 122. Failure test "sni" option
286s ...................................................................... succeeded
286s 131. Resume session with PSK authentication
286s ...................................................................... succeeded
286s 132. Cache session resumption with TLSv1.2
286s ...................................................................... succeeded
286s 133. Test enabled "sessionResume" option
286s ...................................................................... succeeded
286s 134. Test disabled "sessionResume" option
286s ...................................................................... succeeded
286s 141. Stateless session ticket resumption with TLSv1.2
287s ...................................................................... succeeded
287s 142. Stateless session ticket resumption with TLSv1.3
287s ...................................................................... succeeded
287s 151. Session resumption with secret keys
288s ...................................................................... succeeded
288s 161. Redirect connection (wrong peer certificate)
288s ...................................................................... succeeded
288s 162. Redirect connection (no peer certificate)
288s ...................................................................... succeeded
288s 163. Not redirected connection (valid peer certificate)
288s ...................................................................... succeeded
288s 164. Redirect connection (wrong chain)
288s ...................................................................... succeeded
288s 165. Redirect connection (no chain)
289s ...................................................................... succeeded
289s 166. Not redirected connection (valid chain)
289s ...................................................................... succeeded
289s 171. Redirect proxy connection (wrong peer certificate)
289s ...................................................................... succeeded
289s 172. Redirect proxy connection (no peer certificate)
289s ...................................................................... succeeded
289s 173. Not redirected proxy connection (valid peer certificate)
289s ...................................................................... succeeded
289s 181. Session resumption and redirect (wrong certificate)
289s ...................................................................... succeeded
289s 182. Session resumption and redirect (no certificate)
289s ...................................................................... succeeded
289s 183. Session resumption and no redirect (valid certificate)
289s ...................................................................... succeeded
289s 191. Reload configuration
289s ...................................................................... succeeded
289s 201. Failover round robin (rr) strategy
289s ...................................................................... succeeded
289s 202. Failover priority (prio) strategy
290s ...................................................................... succeeded
290s 211. Included configuration files
290s ...................................................................... succeeded
290s 221. Client inetd mode
290s ...................................................................... succeeded
290s 231. Test exec+connect service
290s ...................................................................... succeeded
290s 241. Resume session with delay option
290s ...................................................................... succeeded
290s 242. Resume session with disable delay option
291s ...................................................................... succeeded
291s 251. Wrong client configuration
291s ...................................................................... succeeded
291s 261. Wrong server configuration
291s ...................................................................... succeeded
291s 271. Test OCSP stapling
291s ...................................................................... succeeded
291s 272. Test OCSP client-driven
291s ...................................................................... succeeded
291s 273. Failure test OCSP stapling
291s ...................................................................... succeeded
291s 274. Failure test OCSP client-driven
291s ...................................................................... succeeded
291s 
291s Summary:
291s 	succeeded: 50
291s 	failed: 0
291s 	skipped: 4
291s 
291s Testing results: /tmp/autopkgtest.FhbnhQ/build.W5m/src/tests/logs/results.log
292s autopkgtest [15:58:06]: test upstream: -----------------------]
292s upstream             PASS
292s autopkgtest [15:58:06]: test upstream:  - - - - - - - - - - results - - - - - - - - - -
293s autopkgtest [15:58:07]: @@@@@@@@@@@@@@@@@@@@ summary
293s debian-python        PASS
293s upstream             PASS
305s nova [W] Using flock in prodstack6-ppc64el
305s Creating nova instance adt-plucky-ppc64el-stunnel4-20241113-144935-juju-7f2275-prod-proposed-migration-environment-20-67c04338-af79-4d03-856c-f1e4abaf141d from image adt/ubuntu-plucky-ppc64el-server-20241113.img (UUID 0c5715b6-5cca-4485-b8bf-b85dfd917a5f)...
305s nova [W] Using flock in prodstack6-ppc64el
305s Creating nova instance adt-plucky-ppc64el-stunnel4-20241113-144935-juju-7f2275-prod-proposed-migration-environment-20-67c04338-af79-4d03-856c-f1e4abaf141d from image adt/ubuntu-plucky-ppc64el-server-20241113.img (UUID 0c5715b6-5cca-4485-b8bf-b85dfd917a5f)...