0s autopkgtest [16:58:50]: starting date and time: 2025-03-15 16:58:50+0000 0s autopkgtest [16:58:50]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [16:58:50]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7mabbzje/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-27.secgroup --name adt-plucky-arm64-stunnel4-20250315-165850-juju-7f2275-prod-proposed-migration-environment-20-3698e30d-2552-4205-9b13-e10c4900f0eb --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 161s autopkgtest [17:01:31]: testbed dpkg architecture: arm64 161s autopkgtest [17:01:31]: testbed apt version: 2.9.33 161s autopkgtest [17:01:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 161s autopkgtest [17:01:31]: testbed release detected to be: None 162s autopkgtest [17:01:32]: updating testbed package index (apt update) 163s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 163s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 163s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 163s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 163s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 163s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 164s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 164s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [111 kB] 164s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 c-n-f Metadata [1856 B] 164s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 c-n-f Metadata [116 B] 164s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [324 kB] 164s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 c-n-f Metadata [14.7 kB] 164s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [4948 B] 164s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 c-n-f Metadata [268 B] 165s Fetched 1078 kB in 2s (591 kB/s) 165s Reading package lists... 166s Reading package lists... 166s Building dependency tree... 166s Reading state information... 167s Calculating upgrade... 167s Calculating upgrade... 168s The following packages will be upgraded: 168s pinentry-curses python3-jinja2 strace 168s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 168s Need to get 647 kB of archives. 168s After this operation, 11.3 kB of additional disk space will be used. 168s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 strace arm64 6.13+ds-1ubuntu1 [499 kB] 168s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 pinentry-curses arm64 1.3.1-2ubuntu3 [39.2 kB] 168s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 169s Fetched 647 kB in 1s (646 kB/s) 169s (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 ... 117701 files and directories currently installed.) 169s Preparing to unpack .../strace_6.13+ds-1ubuntu1_arm64.deb ... 169s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 169s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_arm64.deb ... 169s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 169s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 170s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 170s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 170s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 170s Setting up strace (6.13+ds-1ubuntu1) ... 170s Processing triggers for man-db (2.13.0-1) ... 171s Reading package lists... 171s Building dependency tree... 171s Reading state information... 171s Solving dependencies... 172s The following packages will be REMOVED: 172s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 172s libunwind8* linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 172s linux-image-6.11.0-8-generic* linux-modules-6.11.0-8-generic* 172s linux-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 172s 0 upgraded, 0 newly installed, 11 to remove and 5 not upgraded. 172s After this operation, 267 MB disk space will be freed. 172s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 117701 files and directories currently installed.) 172s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 172s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 172s Removing libpython3.12t64:arm64 (3.12.9-1) ... 172s Removing libpython3.12-stdlib:arm64 (3.12.9-1) ... 172s Removing libnsl2:arm64 (1.3.0-3build3) ... 172s Removing libpython3.12-minimal:arm64 (3.12.9-1) ... 173s Removing libunwind8:arm64 (1.6.2-3.1) ... 173s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 173s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 175s Removing linux-image-6.11.0-8-generic (6.11.0-8.8) ... 175s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-10-generic 175s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-10-generic 175s /etc/kernel/postrm.d/initramfs-tools: 175s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 175s /etc/kernel/postrm.d/zz-flash-kernel: 175s flash-kernel: Kernel 6.11.0-8-generic has been removed. 175s flash-kernel: A higher version (6.14.0-10-generic) is still installed, no reflashing required. 175s /etc/kernel/postrm.d/zz-update-grub: 175s Sourcing file `/etc/default/grub' 175s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 175s Generating grub configuration file ... 175s Found linux image: /boot/vmlinuz-6.14.0-10-generic 175s Found initrd image: /boot/initrd.img-6.14.0-10-generic 176s Warning: os-prober will not be executed to detect other bootable partitions. 176s Systems on them will not be added to the GRUB boot configuration. 176s Check GRUB_DISABLE_OS_PROBER documentation entry. 176s Adding boot menu entry for UEFI Firmware Settings ... 176s done 176s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 176s Processing triggers for libc-bin (2.41-1ubuntu1) ... 176s (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 ... 81650 files and directories currently installed.) 176s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8) ... 176s Purging configuration files for libpython3.12-minimal:arm64 (3.12.9-1) ... 177s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 177s autopkgtest [17:01:47]: upgrading testbed (apt dist-upgrade and autopurge) 177s Reading package lists... 178s Building dependency tree... 178s Reading state information... 178s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 178s Starting 2 pkgProblemResolver with broken count: 0 178s Done 179s Entering ResolveByKeep 179s 180s Calculating upgrade... 180s The following packages will be upgraded: 180s libc-bin libc-dev-bin libc6 libc6-dev locales 180s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 180s Need to get 9530 kB of archives. 180s After this operation, 0 B of additional disk space will be used. 180s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6-dev arm64 2.41-1ubuntu2 [1750 kB] 182s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-dev-bin arm64 2.41-1ubuntu2 [24.0 kB] 182s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6 arm64 2.41-1ubuntu2 [2910 kB] 186s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-bin arm64 2.41-1ubuntu2 [600 kB] 186s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 locales all 2.41-1ubuntu2 [4246 kB] 191s Preconfiguring packages ... 191s Fetched 9530 kB in 11s (892 kB/s) 191s (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 ... 81647 files and directories currently installed.) 191s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_arm64.deb ... 191s Unpacking libc6-dev:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 191s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_arm64.deb ... 191s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 191s Preparing to unpack .../libc6_2.41-1ubuntu2_arm64.deb ... 192s Unpacking libc6:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 192s Setting up libc6:arm64 (2.41-1ubuntu2) ... 192s (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 ... 81647 files and directories currently installed.) 192s Preparing to unpack .../libc-bin_2.41-1ubuntu2_arm64.deb ... 192s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 192s Setting up libc-bin (2.41-1ubuntu2) ... 192s (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 ... 81647 files and directories currently installed.) 192s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 192s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 192s Setting up locales (2.41-1ubuntu2) ... 193s Generating locales (this might take a while)... 195s en_US.UTF-8... done 195s Generation complete. 195s Setting up libc-dev-bin (2.41-1ubuntu2) ... 195s Setting up libc6-dev:arm64 (2.41-1ubuntu2) ... 195s Processing triggers for man-db (2.13.0-1) ... 196s Processing triggers for systemd (257.3-1ubuntu3) ... 198s Reading package lists... 198s Building dependency tree... 198s Reading state information... 198s Starting pkgProblemResolver with broken count: 0 198s Starting 2 pkgProblemResolver with broken count: 0 198s Done 199s Solving dependencies... 199s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 200s autopkgtest [17:02:10]: rebooting testbed after setup commands that affected boot 223s autopkgtest [17:02:33]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP PREEMPT_DYNAMIC Wed Mar 12 15:45:31 UTC 2025 226s autopkgtest [17:02:36]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 229s Get:1 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (dsc) [2845 B] 229s Get:2 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (tar) [904 kB] 229s Get:3 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (asc) [833 B] 229s Get:4 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (diff) [54.8 kB] 229s gpgv: Signature made Tue Jan 28 14:01:27 2025 UTC 229s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 229s gpgv: issuer "roam@debian.org" 229s gpgv: Can't check signature: No public key 229s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.74-2.dsc: no acceptable signature found 229s autopkgtest [17:02:39]: testing package stunnel4 version 3:5.74-2 230s autopkgtest [17:02:40]: build not needed 230s autopkgtest [17:02:40]: test debian-python: preparing testbed 231s Reading package lists... 231s Building dependency tree... 231s Reading state information... 231s Starting pkgProblemResolver with broken count: 0 231s Starting 2 pkgProblemResolver with broken count: 0 231s Done 232s The following NEW packages will be installed: 232s python3-all stunnel4 232s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 232s Need to get 222 kB of archives. 232s After this operation, 659 kB of additional disk space will be used. 232s Get:1 http://ftpmaster.internal/ubuntu plucky/universe arm64 stunnel4 arm64 3:5.74-2 [221 kB] 233s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-all arm64 3.13.2-2 [886 B] 233s Fetched 222 kB in 1s (366 kB/s) 233s Selecting previously unselected package stunnel4. 233s (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 ... 81647 files and directories currently installed.) 233s Preparing to unpack .../stunnel4_3%3a5.74-2_arm64.deb ... 233s Unpacking stunnel4 (3:5.74-2) ... 234s Selecting previously unselected package python3-all. 234s Preparing to unpack .../python3-all_3.13.2-2_arm64.deb ... 234s Unpacking python3-all (3.13.2-2) ... 234s Setting up stunnel4 (3:5.74-2) ... 234s Creating group 'stunnel4' with GID 988. 234s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 234s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 234s Setting up python3-all (3.13.2-2) ... 234s Processing triggers for man-db (2.13.0-1) ... 236s autopkgtest [17:02:46]: 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 236s autopkgtest [17:02:46]: test debian-python: [----------------------- 236s 236s 236s ====== python3.13 236s 236s Using /tmp/struntime.h6ongm9z as a temporary directory 236s Trying to obtain the version of /usr/bin/stunnel4 236s Started `/usr/bin/stunnel4 -version` as process 1436 236s Got stunnel version 5.74 236s [main] Starting to do things 236s [main] Awaiting the 'listener started' event 236s [find_listening_port] Looking for a port to listen on 236s [find_listening_port] Trying localhost port 6502 236s [listener] Telling the main thread about [127.0.0.1]:6502 236s [listener] Awaiting client connections... 236s [main] Apparently we are listening on [127.0.0.1]:6502 236s [main] Testing cleartext connections 236s [test_connect [127.0.0.1]:6502 0] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 1] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 2] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 3] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 4] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 5] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 6] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 7] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 8] Trying port 6502 encrypted False 236s [test_connect [127.0.0.1]:6502 9] Trying port 6502 encrypted False 237s [test_connect [127.0.0.1]:6502 0] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49238] New connection 237s [[127.0.0.1]:49238] Telling the main thread 237s [[127.0.0.1]:49238] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 0] Connected to the server: [127.0.0.1]:49238 237s [test_connect [127.0.0.1]:6502 1] Opening an unencrypted connection 237s [test_connect [127.0.0.1]:6502 7] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49254] New connection 237s [[127.0.0.1]:49254] Telling the main thread 237s [[127.0.0.1]:49254] Let them tell us something... 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49256] New connection 237s [[127.0.0.1]:49256] Telling the main thread 237s [[127.0.0.1]:49256] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 1] Connected to the server: [127.0.0.1]:49254 237s [test_connect [127.0.0.1]:6502 7] Connected to the server: [127.0.0.1]:49256 237s [test_connect [127.0.0.1]:6502 6] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49270] New connection 237s [[127.0.0.1]:49270] Telling the main thread 237s [[127.0.0.1]:49270] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 6] Connected to the server: [127.0.0.1]:49270 237s [test_connect [127.0.0.1]:6502 9] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49280] New connection 237s [[127.0.0.1]:49280] Telling the main thread 237s [[127.0.0.1]:49280] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 9] Connected to the server: [127.0.0.1]:49280 237s [test_connect [127.0.0.1]:6502 8] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49294] New connection 237s [[127.0.0.1]:49294] Telling the main thread 237s [[127.0.0.1]:49294] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 8] Connected to the server: [127.0.0.1]:49294 237s [test_connect [127.0.0.1]:6502 7] Sending something... 237s [test_connect [127.0.0.1]:6502 7] Waiting for the main thread to figure it out 237s [[127.0.0.1]:49256] Telling the main thread about b'Hello 7\n' 237s [[127.0.0.1]:49256] Writing something back... 237s [[127.0.0.1]:49256] Telling the main thread about b'There!\n' 237s [[127.0.0.1]:49256] Closing down the writer socket 237s [test_connect [127.0.0.1]:6502 7] The listener acknowledged [127.0.0.1]:49256 237s [test_connect [127.0.0.1]:6502 7] Waiting for the server to receive it... 237s [test_connect [127.0.0.1]:6502 7] Waiting for the server to send something 237s [test_connect [127.0.0.1]:6502 7] Trying to receive the actual data 237s [test_connect [127.0.0.1]:6502 7] Waiting for the server to close the connection 237s [[127.0.0.1]:49256] Telling the main thread done 237s [test_connect [127.0.0.1]:6502 7] Waiting for an EOF on the reader socket 237s [test_connect [127.0.0.1]:6502 7] Closing our writer socket, too 237s [test_connect [127.0.0.1]:6502 7] Letting the main thread know we're done 237s [test_connect [127.0.0.1]:6502 7] Done 237s [process_connections] Fetching task plain-7 237s [process_connections] Waiting for task plain-7 237s [process_connections] Done with task plain-7 237s [test_connect [127.0.0.1]:6502 4] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49296] New connection 237s [[127.0.0.1]:49296] Telling the main thread 237s [[127.0.0.1]:49296] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 4] Connected to the server: [127.0.0.1]:49296 237s [test_connect [127.0.0.1]:6502 3] Opening an unencrypted connection 237s [test_connect [127.0.0.1]:6502 5] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49312] New connection 237s [[127.0.0.1]:49312] Telling the main thread 237s [[127.0.0.1]:49312] Let them tell us something... 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49314] New connection 237s [[127.0.0.1]:49314] Telling the main thread 237s [[127.0.0.1]:49314] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 3] Connected to the server: [127.0.0.1]:49312 237s [test_connect [127.0.0.1]:6502 5] Connected to the server: [127.0.0.1]:49314 237s [test_connect [127.0.0.1]:6502 2] Opening an unencrypted connection 237s [listener] A client connected from somewhere 237s [[127.0.0.1]:49324] New connection 237s [[127.0.0.1]:49324] Telling the main thread 237s [[127.0.0.1]:49324] Let them tell us something... 237s [test_connect [127.0.0.1]:6502 2] Connected to the server: [127.0.0.1]:49324 237s [test_connect [127.0.0.1]:6502 0] Sending something... 237s [test_connect [127.0.0.1]:6502 0] Waiting for the main thread to figure it out 237s [[127.0.0.1]:49238] Telling the main thread about b'Hello 0\n' 237s [[127.0.0.1]:49238] Writing something back... 237s [[127.0.0.1]:49238] Telling the main thread about b'There!\n' 237s [[127.0.0.1]:49238] Closing down the writer socket 237s [test_connect [127.0.0.1]:6502 0] The listener acknowledged [127.0.0.1]:49238 237s [test_connect [127.0.0.1]:6502 0] Waiting for the server to receive it... 237s [test_connect [127.0.0.1]:6502 0] Waiting for the server to send something 237s [test_connect [127.0.0.1]:6502 0] Trying to receive the actual data 237s [test_connect [127.0.0.1]:6502 0] Waiting for the server to close the connection 237s [[127.0.0.1]:49238] Telling the main thread done 237s [test_connect [127.0.0.1]:6502 0] Waiting for an EOF on the reader socket 237s [test_connect [127.0.0.1]:6502 0] Closing our writer socket, too 237s [test_connect [127.0.0.1]:6502 0] Letting the main thread know we're done 237s [test_connect [127.0.0.1]:6502 0] Done 237s [process_connections] Fetching task plain-0 237s [process_connections] Waiting for task plain-0 237s [process_connections] Done with task plain-0 238s [test_connect [127.0.0.1]:6502 5] Sending something... 238s [test_connect [127.0.0.1]:6502 5] Waiting for the main thread to figure it out 238s [[127.0.0.1]:49314] Telling the main thread about b'Hello 5\n' 238s [[127.0.0.1]:49314] Writing something back... 238s [[127.0.0.1]:49314] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49314] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 5] The listener acknowledged [127.0.0.1]:49314 238s [test_connect [127.0.0.1]:6502 5] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 5] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 5] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 5] Waiting for the server to close the connection 238s [[127.0.0.1]:49314] Telling the main thread done 238s [test_connect [127.0.0.1]:6502 5] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 5] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 5] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 5] Done 238s [process_connections] Fetching task plain-5 238s [process_connections] Waiting for task plain-5 238s [process_connections] Done with task plain-5 238s [test_connect [127.0.0.1]:6502 6] Sending something... 238s [test_connect [127.0.0.1]:6502 6] Waiting for the main thread to figure it out 238s [test_connect [127.0.0.1]:6502 9] Sending something... 238s [test_connect [127.0.0.1]:6502 9] Waiting for the main thread to figure it out 238s [[127.0.0.1]:49270] Telling the main thread about b'Hello 6\n' 238s [[127.0.0.1]:49270] Writing something back... 238s [[127.0.0.1]:49270] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49270] Closing down the writer socket 238s [[127.0.0.1]:49280] Telling the main thread about b'Hello 9\n' 238s [[127.0.0.1]:49280] Writing something back... 238s [[127.0.0.1]:49280] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49280] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 3] Sending something... 238s [test_connect [127.0.0.1]:6502 3] Waiting for the main thread to figure it out 238s [test_connect [127.0.0.1]:6502 2] Sending something... 238s [test_connect [127.0.0.1]:6502 2] Waiting for the main thread to figure it out 238s [test_connect [127.0.0.1]:6502 6] The listener acknowledged [127.0.0.1]:49270 238s [test_connect [127.0.0.1]:6502 6] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 6] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 6] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 6] Waiting for the server to close the connection 238s [test_connect [127.0.0.1]:6502 9] The listener acknowledged [127.0.0.1]:49280 238s [test_connect [127.0.0.1]:6502 9] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 9] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 9] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 9] Waiting for the server to close the connection 238s [[127.0.0.1]:49270] Telling the main thread done 238s [[127.0.0.1]:49280] Telling the main thread done 238s [[127.0.0.1]:49312] Telling the main thread about b'Hello 3\n' 238s [[127.0.0.1]:49312] Writing something back... 238s [[127.0.0.1]:49312] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49312] Closing down the writer socket 238s [[127.0.0.1]:49324] Telling the main thread about b'Hello 2\n' 238s [[127.0.0.1]:49324] Writing something back... 238s [[127.0.0.1]:49324] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49324] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 1] Sending something... 238s [test_connect [127.0.0.1]:6502 1] Waiting for the main thread to figure it out 238s [test_connect [127.0.0.1]:6502 6] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 6] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 9] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 9] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 3] The listener acknowledged [127.0.0.1]:49312 238s [test_connect [127.0.0.1]:6502 3] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 3] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 3] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 3] Waiting for the server to close the connection 238s [test_connect [127.0.0.1]:6502 2] The listener acknowledged [127.0.0.1]:49324 238s [test_connect [127.0.0.1]:6502 2] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 2] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 2] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 2] Waiting for the server to close the connection 238s [[127.0.0.1]:49312] Telling the main thread done 238s [[127.0.0.1]:49324] Telling the main thread done 238s [[127.0.0.1]:49254] Telling the main thread about b'Hello 1\n' 238s [[127.0.0.1]:49254] Writing something back... 238s [[127.0.0.1]:49254] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49254] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 6] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 6] Done 238s [test_connect [127.0.0.1]:6502 9] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 9] Done 238s [test_connect [127.0.0.1]:6502 3] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 3] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 2] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 2] Closing our writer socket, too 238s [process_connections] Fetching task plain-6 238s [process_connections] Waiting for task plain-6 238s [process_connections] Done with task plain-6 238s [process_connections] Fetching task plain-9 238s [process_connections] Waiting for task plain-9 238s [process_connections] Done with task plain-9 238s [test_connect [127.0.0.1]:6502 1] The listener acknowledged [127.0.0.1]:49254 238s [test_connect [127.0.0.1]:6502 1] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 1] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 1] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 1] Waiting for the server to close the connection 238s [[127.0.0.1]:49254] Telling the main thread done 238s [test_connect [127.0.0.1]:6502 3] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 3] Done 238s [test_connect [127.0.0.1]:6502 2] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 2] Done 238s [process_connections] Fetching task plain-3 238s [process_connections] Waiting for task plain-3 238s [process_connections] Done with task plain-3 238s [process_connections] Fetching task plain-2 238s [process_connections] Waiting for task plain-2 238s [process_connections] Done with task plain-2 238s [test_connect [127.0.0.1]:6502 1] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 1] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 1] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 1] Done 238s [process_connections] Fetching task plain-1 238s [process_connections] Waiting for task plain-1 238s [process_connections] Done with task plain-1 238s [test_connect [127.0.0.1]:6502 4] Sending something... 238s [test_connect [127.0.0.1]:6502 4] Waiting for the main thread to figure it out 238s [[127.0.0.1]:49296] Telling the main thread about b'Hello 4\n' 238s [[127.0.0.1]:49296] Writing something back... 238s [[127.0.0.1]:49296] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49296] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 4] The listener acknowledged [127.0.0.1]:49296 238s [test_connect [127.0.0.1]:6502 4] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 4] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 4] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 4] Waiting for the server to close the connection 238s [[127.0.0.1]:49296] Telling the main thread done 238s [test_connect [127.0.0.1]:6502 4] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 4] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 4] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 4] Done 238s [process_connections] Fetching task plain-4 238s [process_connections] Waiting for task plain-4 238s [process_connections] Done with task plain-4 238s [test_connect [127.0.0.1]:6502 8] Sending something... 238s [test_connect [127.0.0.1]:6502 8] Waiting for the main thread to figure it out 238s [[127.0.0.1]:49294] Telling the main thread about b'Hello 8\n' 238s [[127.0.0.1]:49294] Writing something back... 238s [[127.0.0.1]:49294] Telling the main thread about b'There!\n' 238s [[127.0.0.1]:49294] Closing down the writer socket 238s [test_connect [127.0.0.1]:6502 8] The listener acknowledged [127.0.0.1]:49294 238s [test_connect [127.0.0.1]:6502 8] Waiting for the server to receive it... 238s [test_connect [127.0.0.1]:6502 8] Waiting for the server to send something 238s [test_connect [127.0.0.1]:6502 8] Trying to receive the actual data 238s [test_connect [127.0.0.1]:6502 8] Waiting for the server to close the connection 238s [[127.0.0.1]:49294] Telling the main thread done 238s [test_connect [127.0.0.1]:6502 8] Waiting for an EOF on the reader socket 238s [test_connect [127.0.0.1]:6502 8] Closing our writer socket, too 238s [test_connect [127.0.0.1]:6502 8] Letting the main thread know we're done 238s [test_connect [127.0.0.1]:6502 8] Done 238s [process_connections] Fetching task plain-8 238s [process_connections] Waiting for task plain-8 238s [process_connections] Done with task plain-8 238s [main] Picking a listen address:port for stunnel 238s [find_listening_port] Looking for a port to listen on 238s [find_listening_port] Trying 127.0.0.1 port 6503 238s [find_listening_port] got 6503, shutting down the listener 238s [main] Will put stunnel at 127.0.0.1:6503 238s [main] Preparing the stunnel config file 238s [main] Using config file /tmp/struntime.h6ongm9z/stunnel.conf 238s [main] 238s [main] pid = /tmp/struntime.h6ongm9z/stunnel.pid 238s [main] foreground = yes 238s [main] 238s [main] cert = /tmp/autopkgtest.vojCYX/build.XVP/src/debian/tests/certs/certificate.pem 238s [main] key = /tmp/struntime.h6ongm9z/key.pem 238s [main] 238s [main] [test] 238s [main] accept = 127.0.0.1:6503 238s [main] connect = 127.0.0.1:6502 238s [main] End of the config file 238s [main] Launching the stunnel server 238s [main] - got pid 1440 238s [main] Launching the stunnel output pipe thread 238s [main] Waiting for stunnel to start up 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: stunnel 5.74 on aarch64-unknown-linux-gnu platform' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: Compiled with OpenSSL 3.4.0 22 Oct 2024' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: Running with OpenSSL 3.4.1 11 Feb 2025' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: Reading configuration from file /tmp/struntime.h6ongm9z/stunnel.conf' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: UTF-8 byte order mark not detected' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: FIPS mode disabled' 238s [stunnel_output] Read '2025.03.15 17:02:48 LOG5[ui]: Configuration successful' 238s [stunnel_output] The main thread ought to know 238s [main] Testing the encrypted connections 238s [test_connect [127.0.0.1]:6503 0] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 1] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 2] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 3] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 4] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 5] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 6] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 7] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 8] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 9] Trying port 6503 encrypted True 238s [test_connect [127.0.0.1]:6503 4] Creating an SSL context 238s [test_connect [127.0.0.1]:6503 4] - cert required 238s [test_connect [127.0.0.1]:6503 4] - load_verify_locations() 238s [test_connect [127.0.0.1]:6503 4] Opening an SSL connection 238s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[0]: Service [test] accepted connection from 127.0.0.1:59784' 238s [test_connect [127.0.0.1]:6503 4] Connected to the server: [127.0.0.1]:59784 238s [listener] A client connected from somewhere 238s [[127.0.0.1]:49330] New connection 238s [[127.0.0.1]:49330] Telling the main thread 238s [[127.0.0.1]:49330] Let them tell us something... 238s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[0]: s_connect: connected 127.0.0.1:6502' 238s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[0]: Service [test] connected remote server from 127.0.0.1:49330' 239s [test_connect [127.0.0.1]:6503 3] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 3] - cert required 239s [test_connect [127.0.0.1]:6503 3] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 3] Opening an SSL connection 239s [test_connect [127.0.0.1]:6503 6] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 6] - cert required 239s [test_connect [127.0.0.1]:6503 6] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 6] Opening an SSL connection 239s [test_connect [127.0.0.1]:6503 8] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 8] - cert required 239s [test_connect [127.0.0.1]:6503 8] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 8] Opening an SSL connection 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[1]: Service [test] accepted connection from 127.0.0.1:59796' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[2]: Service [test] accepted connection from 127.0.0.1:59812' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[3]: Service [test] accepted connection from 127.0.0.1:59828' 239s [test_connect [127.0.0.1]:6503 6] Connected to the server: [127.0.0.1]:59812 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[2]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[2]: Service [test] connected remote server from 127.0.0.1:49332' 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49332] New connection 239s [[127.0.0.1]:49332] Telling the main thread 239s [[127.0.0.1]:49332] Let them tell us something... 239s [test_connect [127.0.0.1]:6503 8] Connected to the server: [127.0.0.1]:59828 239s [test_connect [127.0.0.1]:6503 3] Connected to the server: [127.0.0.1]:59796 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[3]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[3]: Service [test] connected remote server from 127.0.0.1:49340' 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49340] New connection 239s [[127.0.0.1]:49340] Telling the main thread 239s [[127.0.0.1]:49340] Let them tell us something... 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[1]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[1]: Service [test] connected remote server from 127.0.0.1:49344' 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49344] New connection 239s [[127.0.0.1]:49344] Telling the main thread 239s [[127.0.0.1]:49344] Let them tell us something... 239s [test_connect [127.0.0.1]:6503 7] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 7] - cert required 239s [test_connect [127.0.0.1]:6503 7] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 7] Opening an SSL connection 239s [test_connect [127.0.0.1]:6503 9] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 9] - cert required 239s [test_connect [127.0.0.1]:6503 9] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 9] Opening an SSL connection 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[4]: Service [test] accepted connection from 127.0.0.1:59830' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[5]: Service [test] accepted connection from 127.0.0.1:59838' 239s [test_connect [127.0.0.1]:6503 7] Connected to the server: [127.0.0.1]:59830 239s [test_connect [127.0.0.1]:6503 9] Connected to the server: [127.0.0.1]:59838 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[4]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[4]: Service [test] connected remote server from 127.0.0.1:49360' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[5]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[5]: Service [test] connected remote server from 127.0.0.1:49376' 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49360] New connection 239s [[127.0.0.1]:49360] Telling the main thread 239s [[127.0.0.1]:49360] Let them tell us something... 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49376] New connection 239s [[127.0.0.1]:49376] Telling the main thread 239s [[127.0.0.1]:49376] Let them tell us something... 239s [test_connect [127.0.0.1]:6503 0] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 0] - cert required 239s [test_connect [127.0.0.1]:6503 0] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 0] Opening an SSL connection 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[6]: Service [test] accepted connection from 127.0.0.1:59850' 239s [test_connect [127.0.0.1]:6503 0] Connected to the server: [127.0.0.1]:59850 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[6]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[6]: Service [test] connected remote server from 127.0.0.1:49378' 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49378] New connection 239s [[127.0.0.1]:49378] Telling the main thread 239s [[127.0.0.1]:49378] Let them tell us something... 239s [test_connect [127.0.0.1]:6503 2] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 2] - cert required 239s [test_connect [127.0.0.1]:6503 2] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 2] Opening an SSL connection 239s [test_connect [127.0.0.1]:6503 5] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 5] - cert required 239s [test_connect [127.0.0.1]:6503 5] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 5] Opening an SSL connection 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[7]: Service [test] accepted connection from 127.0.0.1:59854' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[8]: Service [test] accepted connection from 127.0.0.1:59868' 239s [test_connect [127.0.0.1]:6503 5] Connected to the server: [127.0.0.1]:59868 239s [test_connect [127.0.0.1]:6503 2] Connected to the server: [127.0.0.1]:59854 239s [test_connect [127.0.0.1]:6503 9] Sending something... 239s [test_connect [127.0.0.1]:6503 9] Waiting for the main thread to figure it out 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[8]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[8]: Service [test] connected remote server from 127.0.0.1:49380' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[7]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[7]: Service [test] connected remote server from 127.0.0.1:49386' 239s [[127.0.0.1]:49376] Telling the main thread about b'Hello 9\n' 239s [[127.0.0.1]:49376] Writing something back... 239s [[127.0.0.1]:49376] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49376] Closing down the writer socket 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49380] New connection 239s [[127.0.0.1]:49380] Telling the main thread 239s [[127.0.0.1]:49380] Let them tell us something... 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49386] New connection 239s [[127.0.0.1]:49386] Telling the main thread 239s [[127.0.0.1]:49386] Let them tell us something... 239s [test_connect [127.0.0.1]:6503 9] The listener acknowledged [127.0.0.1]:49376 239s [test_connect [127.0.0.1]:6503 9] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 9] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 9] Trying to receive the actual data 239s [[127.0.0.1]:49376] Telling the main thread done 239s [test_connect [127.0.0.1]:6503 4] Sending something... 239s [test_connect [127.0.0.1]:6503 4] Waiting for the main thread to figure it out 239s [test_connect [127.0.0.1]:6503 9] Waiting for the server to close the connection 239s [test_connect [127.0.0.1]:6503 9] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 9] Closing our writer socket, too 239s [[127.0.0.1]:49330] Telling the main thread about b'Hello 4\n' 239s [[127.0.0.1]:49330] Writing something back... 239s [[127.0.0.1]:49330] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49330] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 9] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 9] Done 239s [test_connect [127.0.0.1]:6503 4] The listener acknowledged [127.0.0.1]:49330 239s [test_connect [127.0.0.1]:6503 4] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 4] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 4] Trying to receive the actual data 239s [test_connect [127.0.0.1]:6503 4] Waiting for the server to close the connection 239s [[127.0.0.1]:49330] Telling the main thread done 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-9 239s [process_connections] Waiting for task encr-9 239s [process_connections] Done with task encr-9 239s [test_connect [127.0.0.1]:6503 4] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 4] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 4] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 4] Done 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-4 239s [process_connections] Waiting for task encr-4 239s [process_connections] Done with task encr-4 239s [test_connect [127.0.0.1]:6503 1] Creating an SSL context 239s [test_connect [127.0.0.1]:6503 1] - cert required 239s [test_connect [127.0.0.1]:6503 1] - load_verify_locations() 239s [test_connect [127.0.0.1]:6503 1] Opening an SSL connection 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[9]: Service [test] accepted connection from 127.0.0.1:59880' 239s [test_connect [127.0.0.1]:6503 1] Connected to the server: [127.0.0.1]:59880 239s [listener] A client connected from somewhere 239s [[127.0.0.1]:49394] New connection 239s [[127.0.0.1]:49394] Telling the main thread 239s [[127.0.0.1]:49394] Let them tell us something... 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[9]: s_connect: connected 127.0.0.1:6502' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[9]: Service [test] connected remote server from 127.0.0.1:49394' 239s [test_connect [127.0.0.1]:6503 6] Sending something... 239s [test_connect [127.0.0.1]:6503 6] Waiting for the main thread to figure it out 239s [[127.0.0.1]:49332] Telling the main thread about b'Hello 6\n' 239s [[127.0.0.1]:49332] Writing something back... 239s [[127.0.0.1]:49332] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49332] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 6] The listener acknowledged [127.0.0.1]:49332 239s [test_connect [127.0.0.1]:6503 6] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 6] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 6] Trying to receive the actual data 239s [test_connect [127.0.0.1]:6503 6] Waiting for the server to close the connection 239s [[127.0.0.1]:49332] Telling the main thread done 239s [test_connect [127.0.0.1]:6503 6] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 6] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 6] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 6] Done 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-6 239s [process_connections] Waiting for task encr-6 239s [process_connections] Done with task encr-6 239s [test_connect [127.0.0.1]:6503 7] Sending something... 239s [test_connect [127.0.0.1]:6503 7] Waiting for the main thread to figure it out 239s [[127.0.0.1]:49360] Telling the main thread about b'Hello 7\n' 239s [[127.0.0.1]:49360] Writing something back... 239s [[127.0.0.1]:49360] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49360] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 5] Sending something... 239s [test_connect [127.0.0.1]:6503 5] Waiting for the main thread to figure it out 239s [test_connect [127.0.0.1]:6503 7] The listener acknowledged [127.0.0.1]:49360 239s [test_connect [127.0.0.1]:6503 7] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 7] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 7] Trying to receive the actual data 239s [test_connect [127.0.0.1]:6503 7] Waiting for the server to close the connection 239s [[127.0.0.1]:49360] Telling the main thread done 239s [[127.0.0.1]:49380] Telling the main thread about b'Hello 5\n' 239s [[127.0.0.1]:49380] Writing something back... 239s [[127.0.0.1]:49380] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49380] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 7] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 7] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 7] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 7] Done 239s [test_connect [127.0.0.1]:6503 5] The listener acknowledged [127.0.0.1]:49380 239s [test_connect [127.0.0.1]:6503 5] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 5] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 5] Trying to receive the actual data 239s [[127.0.0.1]:49380] Telling the main thread done 239s [process_connections] Fetching task encr-7 239s [process_connections] Waiting for task encr-7 239s [process_connections] Done with task encr-7 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [test_connect [127.0.0.1]:6503 5] Waiting for the server to close the connection 239s [test_connect [127.0.0.1]:6503 5] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 5] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 5] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 5] Done 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-5 239s [process_connections] Waiting for task encr-5 239s [process_connections] Done with task encr-5 239s [test_connect [127.0.0.1]:6503 8] Sending something... 239s [test_connect [127.0.0.1]:6503 8] Waiting for the main thread to figure it out 239s [test_connect [127.0.0.1]:6503 3] Sending something... 239s [test_connect [127.0.0.1]:6503 3] Waiting for the main thread to figure it out 239s [[127.0.0.1]:49340] Telling the main thread about b'Hello 8\n' 239s [[127.0.0.1]:49340] Writing something back... 239s [[127.0.0.1]:49340] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49340] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 8] The listener acknowledged [127.0.0.1]:49340 239s [test_connect [127.0.0.1]:6503 8] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 8] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 8] Trying to receive the actual data 239s [[127.0.0.1]:49340] Telling the main thread done 239s [[127.0.0.1]:49344] Telling the main thread about b'Hello 3\n' 239s [[127.0.0.1]:49344] Writing something back... 239s [[127.0.0.1]:49344] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49344] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 8] Waiting for the server to close the connection 239s [test_connect [127.0.0.1]:6503 8] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 8] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 3] The listener acknowledged [127.0.0.1]:49344 239s [test_connect [127.0.0.1]:6503 3] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 3] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 3] Trying to receive the actual data 239s [test_connect [127.0.0.1]:6503 3] Waiting for the server to close the connection 239s [[127.0.0.1]:49344] Telling the main thread done 239s [test_connect [127.0.0.1]:6503 3] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 3] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 8] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 8] Done 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [stunnel_output] Read '2025.03.15 17:02:49 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-8 239s [process_connections] Waiting for task encr-8 239s [process_connections] Done with task encr-8 239s [test_connect [127.0.0.1]:6503 3] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 3] Done 239s [process_connections] Fetching task encr-3 239s [process_connections] Waiting for task encr-3 239s [process_connections] Done with task encr-3 239s [test_connect [127.0.0.1]:6503 1] Sending something... 239s [test_connect [127.0.0.1]:6503 1] Waiting for the main thread to figure it out 239s [[127.0.0.1]:49394] Telling the main thread about b'Hello 1\n' 239s [[127.0.0.1]:49394] Writing something back... 239s [[127.0.0.1]:49394] Telling the main thread about b'There!\n' 239s [[127.0.0.1]:49394] Closing down the writer socket 239s [test_connect [127.0.0.1]:6503 1] The listener acknowledged [127.0.0.1]:49394 239s [test_connect [127.0.0.1]:6503 1] Waiting for the server to receive it... 239s [test_connect [127.0.0.1]:6503 1] Waiting for the server to send something 239s [test_connect [127.0.0.1]:6503 1] Trying to receive the actual data 239s [[127.0.0.1]:49394] Telling the main thread done 239s [test_connect [127.0.0.1]:6503 1] Waiting for the server to close the connection 239s [test_connect [127.0.0.1]:6503 1] Waiting for an EOF on the reader socket 239s [test_connect [127.0.0.1]:6503 1] Closing our writer socket, too 239s [test_connect [127.0.0.1]:6503 1] Letting the main thread know we're done 239s [test_connect [127.0.0.1]:6503 1] Done 239s [stunnel_output] Read '2025.03.15 17:02:50 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 239s [process_connections] Fetching task encr-1 239s [process_connections] Waiting for task encr-1 239s [process_connections] Done with task encr-1 240s [test_connect [127.0.0.1]:6503 0] Sending something... 240s [test_connect [127.0.0.1]:6503 0] Waiting for the main thread to figure it out 240s [[127.0.0.1]:49378] Telling the main thread about b'Hello 0\n' 240s [[127.0.0.1]:49378] Writing something back... 240s [[127.0.0.1]:49378] Telling the main thread about b'There!\n' 240s [[127.0.0.1]:49378] Closing down the writer socket 240s [test_connect [127.0.0.1]:6503 0] The listener acknowledged [127.0.0.1]:49378 240s [test_connect [127.0.0.1]:6503 0] Waiting for the server to receive it... 240s [test_connect [127.0.0.1]:6503 0] Waiting for the server to send something 240s [test_connect [127.0.0.1]:6503 0] Trying to receive the actual data 240s [test_connect [127.0.0.1]:6503 0] Waiting for the server to close the connection 240s [[127.0.0.1]:49378] Telling the main thread done 240s [test_connect [127.0.0.1]:6503 2] Sending something... 240s [test_connect [127.0.0.1]:6503 2] Waiting for the main thread to figure it out 240s [test_connect [127.0.0.1]:6503 0] Waiting for an EOF on the reader socket 240s [test_connect [127.0.0.1]:6503 0] Closing our writer socket, too 240s [stunnel_output] Read '2025.03.15 17:02:50 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 240s [test_connect [127.0.0.1]:6503 0] Letting the main thread know we're done 240s [test_connect [127.0.0.1]:6503 0] Done 240s [process_connections] Fetching task encr-0 240s [process_connections] Waiting for task encr-0 240s [process_connections] Done with task encr-0 240s [[127.0.0.1]:49386] Telling the main thread about b'Hello 2\n' 240s [[127.0.0.1]:49386] Writing something back... 240s [[127.0.0.1]:49386] Telling the main thread about b'There!\n' 240s [[127.0.0.1]:49386] Closing down the writer socket 240s [test_connect [127.0.0.1]:6503 2] The listener acknowledged [127.0.0.1]:49386 240s [test_connect [127.0.0.1]:6503 2] Waiting for the server to receive it... 240s [test_connect [127.0.0.1]:6503 2] Waiting for the server to send something 240s [test_connect [127.0.0.1]:6503 2] Trying to receive the actual data 240s [test_connect [127.0.0.1]:6503 2] Waiting for the server to close the connection 240s [[127.0.0.1]:49386] Telling the main thread done 240s [test_connect [127.0.0.1]:6503 2] Waiting for an EOF on the reader socket 240s [test_connect [127.0.0.1]:6503 2] Closing our writer socket, too 240s [stunnel_output] Read '2025.03.15 17:02:50 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 240s [test_connect [127.0.0.1]:6503 2] Letting the main thread know we're done 240s [test_connect [127.0.0.1]:6503 2] Done 240s [process_connections] Fetching task encr-2 240s [process_connections] Waiting for task encr-2 240s [process_connections] Done with task encr-2 240s [main] Everything seems to be all right! 240s About to cancel 2 remaining task(s) 240s - listen 240s - output 240s Waiting for the tasks to hopefully finish 240s About to kill and wait for 1 child process(es) 240s - pid 1440 240s Waiting for the processes to exit... 240s Got processes' exit status: [-9] 240s autopkgtest [17:02:50]: test debian-python: -----------------------] 241s debian-python PASS 241s autopkgtest [17:02:51]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 241s autopkgtest [17:02:51]: test upstream: preparing testbed 402s autopkgtest [17:05:31]: testbed dpkg architecture: arm64 402s autopkgtest [17:05:32]: testbed apt version: 2.9.33 402s autopkgtest [17:05:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 402s autopkgtest [17:05:32]: testbed release detected to be: plucky 403s autopkgtest [17:05:33]: updating testbed package index (apt update) 404s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 404s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 404s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 404s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 404s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 404s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 404s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 405s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [111 kB] 405s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 c-n-f Metadata [1856 B] 405s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 c-n-f Metadata [116 B] 405s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [324 kB] 405s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 c-n-f Metadata [14.7 kB] 405s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [4948 B] 405s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 c-n-f Metadata [268 B] 406s Fetched 1078 kB in 2s (580 kB/s) 407s Reading package lists... 408s Reading package lists... 408s Building dependency tree... 408s Reading state information... 409s Calculating upgrade... 409s Calculating upgrade... 410s The following packages will be upgraded: 410s pinentry-curses python3-jinja2 strace 410s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 410s Need to get 647 kB of archives. 410s After this operation, 11.3 kB of additional disk space will be used. 410s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 strace arm64 6.13+ds-1ubuntu1 [499 kB] 410s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 pinentry-curses arm64 1.3.1-2ubuntu3 [39.2 kB] 411s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 411s Fetched 647 kB in 1s (598 kB/s) 412s (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 ... 117701 files and directories currently installed.) 412s Preparing to unpack .../strace_6.13+ds-1ubuntu1_arm64.deb ... 412s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 412s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_arm64.deb ... 412s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 412s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 412s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 412s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 412s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 412s Setting up strace (6.13+ds-1ubuntu1) ... 412s Processing triggers for man-db (2.13.0-1) ... 413s Reading package lists... 414s Building dependency tree... 414s Reading state information... 414s Solving dependencies... 415s The following packages will be REMOVED: 415s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 415s libunwind8* linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 415s linux-image-6.11.0-8-generic* linux-modules-6.11.0-8-generic* 415s linux-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 415s 0 upgraded, 0 newly installed, 11 to remove and 5 not upgraded. 415s After this operation, 267 MB disk space will be freed. 415s (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 ... 117701 files and directories currently installed.) 415s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 415s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 415s Removing libpython3.12t64:arm64 (3.12.9-1) ... 415s Removing libpython3.12-stdlib:arm64 (3.12.9-1) ... 416s Removing libnsl2:arm64 (1.3.0-3build3) ... 416s Removing libpython3.12-minimal:arm64 (3.12.9-1) ... 416s Removing libunwind8:arm64 (1.6.2-3.1) ... 416s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 416s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 418s Removing linux-image-6.11.0-8-generic (6.11.0-8.8) ... 418s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-10-generic 418s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-10-generic 418s /etc/kernel/postrm.d/initramfs-tools: 418s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 419s /etc/kernel/postrm.d/zz-flash-kernel: 419s flash-kernel: Kernel 6.11.0-8-generic has been removed. 419s flash-kernel: A higher version (6.14.0-10-generic) is still installed, no reflashing required. 419s /etc/kernel/postrm.d/zz-update-grub: 419s Sourcing file `/etc/default/grub' 419s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 419s Generating grub configuration file ... 419s Found linux image: /boot/vmlinuz-6.14.0-10-generic 419s Found initrd image: /boot/initrd.img-6.14.0-10-generic 420s Warning: os-prober will not be executed to detect other bootable partitions. 420s Systems on them will not be added to the GRUB boot configuration. 420s Check GRUB_DISABLE_OS_PROBER documentation entry. 420s Adding boot menu entry for UEFI Firmware Settings ... 420s done 420s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 420s Processing triggers for libc-bin (2.41-1ubuntu1) ... 420s (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 ... 81650 files and directories currently installed.) 420s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8) ... 420s Purging configuration files for libpython3.12-minimal:arm64 (3.12.9-1) ... 420s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 421s autopkgtest [17:05:51]: upgrading testbed (apt dist-upgrade and autopurge) 421s Reading package lists... 422s Building dependency tree... 422s Reading state information... 423s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 426s Starting 2 pkgProblemResolver with broken count: 0 426s Done 426s Entering ResolveByKeep 426s 426s Calculating upgrade... 427s The following packages will be upgraded: 427s libc-bin libc-dev-bin libc6 libc6-dev locales 427s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 427s Need to get 9530 kB of archives. 427s After this operation, 0 B of additional disk space will be used. 427s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6-dev arm64 2.41-1ubuntu2 [1750 kB] 429s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-dev-bin arm64 2.41-1ubuntu2 [24.0 kB] 429s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6 arm64 2.41-1ubuntu2 [2910 kB] 432s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-bin arm64 2.41-1ubuntu2 [600 kB] 433s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 locales all 2.41-1ubuntu2 [4246 kB] 438s Preconfiguring packages ... 438s Fetched 9530 kB in 11s (862 kB/s) 439s (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 ... 81647 files and directories currently installed.) 439s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_arm64.deb ... 439s Unpacking libc6-dev:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 439s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_arm64.deb ... 439s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 439s Preparing to unpack .../libc6_2.41-1ubuntu2_arm64.deb ... 439s Unpacking libc6:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 440s Setting up libc6:arm64 (2.41-1ubuntu2) ... 440s (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 ... 81647 files and directories currently installed.) 440s Preparing to unpack .../libc-bin_2.41-1ubuntu2_arm64.deb ... 440s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 440s Setting up libc-bin (2.41-1ubuntu2) ... 440s (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 ... 81647 files and directories currently installed.) 440s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 440s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 440s Setting up locales (2.41-1ubuntu2) ... 442s Generating locales (this might take a while)... 444s en_US.UTF-8... done 444s Generation complete. 444s Setting up libc-dev-bin (2.41-1ubuntu2) ... 444s Setting up libc6-dev:arm64 (2.41-1ubuntu2) ... 444s Processing triggers for man-db (2.13.0-1) ... 445s Processing triggers for systemd (257.3-1ubuntu3) ... 446s Reading package lists... 447s Building dependency tree... 447s Reading state information... 447s Starting pkgProblemResolver with broken count: 0 447s Starting 2 pkgProblemResolver with broken count: 0 447s Done 448s Solving dependencies... 449s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 449s autopkgtest [17:06:19]: rebooting testbed after setup commands that affected boot 479s Reading package lists... 479s Building dependency tree... 479s Reading state information... 479s Starting pkgProblemResolver with broken count: 0 480s Starting 2 pkgProblemResolver with broken count: 0 480s Done 480s The following NEW packages will be installed: 480s net-tools stunnel4 481s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 481s Need to get 428 kB of archives. 481s After this operation, 1878 kB of additional disk space will be used. 481s Get:1 http://ftpmaster.internal/ubuntu plucky/universe arm64 stunnel4 arm64 3:5.74-2 [221 kB] 481s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 net-tools arm64 2.10-1.1ubuntu1 [207 kB] 482s Fetched 428 kB in 1s (429 kB/s) 482s Selecting previously unselected package stunnel4. 482s (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 ... 81647 files and directories currently installed.) 482s Preparing to unpack .../stunnel4_3%3a5.74-2_arm64.deb ... 482s Unpacking stunnel4 (3:5.74-2) ... 482s Selecting previously unselected package net-tools. 482s Preparing to unpack .../net-tools_2.10-1.1ubuntu1_arm64.deb ... 482s Unpacking net-tools (2.10-1.1ubuntu1) ... 483s Setting up stunnel4 (3:5.74-2) ... 483s Creating group 'stunnel4' with GID 988. 483s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 483s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 484s Setting up net-tools (2.10-1.1ubuntu1) ... 484s Processing triggers for man-db (2.13.0-1) ... 488s autopkgtest [17:06:58]: test upstream: debian/tests/upstream 488s autopkgtest [17:06:58]: test upstream: [----------------------- 490s keys & certificates successfully generated 490s ./maketestcert.sh finished 491s Stunnel was compiled and run with different OpenSSL versions 491s autopkgtest [17:07:01]: test upstream: -----------------------] 492s autopkgtest [17:07:02]: test upstream: - - - - - - - - - - results - - - - - - - - - - 492s upstream PASS 494s autopkgtest [17:07:04]: @@@@@@@@@@@@@@@@@@@@ summary 494s debian-python PASS 494s upstream PASS 504s nova [W] Using flock in prodstack6-arm64 504s Creating nova instance adt-plucky-arm64-stunnel4-20250315-165850-juju-7f2275-prod-proposed-migration-environment-20-3698e30d-2552-4205-9b13-e10c4900f0eb from image adt/ubuntu-plucky-arm64-server-20250315.img (UUID bd6e766c-b51f-4b53-86d6-23aa4d18f524)... 504s nova [W] Timed out waiting for d21e0019-1fad-4a29-87c6-c49898ad311f to get deleted. 504s nova [W] Using flock in prodstack6-arm64 504s Creating nova instance adt-plucky-arm64-stunnel4-20250315-165850-juju-7f2275-prod-proposed-migration-environment-20-3698e30d-2552-4205-9b13-e10c4900f0eb from image adt/ubuntu-plucky-arm64-server-20250315.img (UUID bd6e766c-b51f-4b53-86d6-23aa4d18f524)... 504s nova [W] Timed out waiting for 8570a742-b9c7-4bff-bb99-628fb9d9b6e5 to get deleted.