0s autopkgtest [16:38:30]: starting date: 2024-03-09 0s autopkgtest [16:38:30]: git checkout: d9c0295 adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [16:38:30]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.s5sip38f/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:python-cryptography,src:openssl --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python-cryptography/41.0.7-4build2 openssl/3.0.10-1ubuntu5' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-8.secgroup --name adt-noble-arm64-stunnel4-20240309-163830-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 94s autopkgtest [16:40:04]: @@@@@@@@@@@@@@@@@@@@ test bed setup 94s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 95s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [3976 B] 95s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [2635 kB] 95s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [37.3 kB] 95s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [429 kB] 95s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [577 kB] 95s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 95s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [20.3 kB] 95s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 95s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [2881 kB] 95s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 95s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [39.2 kB] 95s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 96s Fetched 6752 kB in 1s (4808 kB/s) 97s Reading package lists... 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Calculating upgrade... 100s The following package was automatically installed and is no longer required: 100s ubuntu-advantage-tools 100s Use 'sudo apt autoremove' to remove it. 100s The following packages will be REMOVED: 100s libssl3 100s The following NEW packages will be installed: 100s libnuma1 libsensors-config libsensors5 libssl3t64 numactl sysstat 100s The following packages will be upgraded: 100s efibootmgr openssl python3-cryptography ubuntu-minimal ubuntu-standard 100s 5 upgraded, 6 newly installed, 1 to remove and 0 not upgraded. 100s Need to get 4154 kB of archives. 100s After this operation, 2289 kB of additional disk space will be used. 100s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu1 [983 kB] 100s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 python3-cryptography arm64 41.0.7-4build2 [772 kB] 100s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu1 [1770 kB] 100s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-minimal arm64 1.535 [10.5 kB] 100s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libnuma1 arm64 2.0.18-1 [23.5 kB] 100s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-standard arm64 1.535 [10.5 kB] 100s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 efibootmgr arm64 18-1build1 [31.5 kB] 100s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 libsensors-config all 1:3.6.0-9 [5458 B] 100s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 libsensors5 arm64 1:3.6.0-9 [26.9 kB] 100s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 numactl arm64 2.0.18-1 [39.5 kB] 100s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 sysstat arm64 12.6.1-1ubuntu1 [480 kB] 101s Preconfiguring packages ... 101s Fetched 4154 kB in 1s (5739 kB/s) 101s (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 ... 74662 files and directories currently installed.) 101s Preparing to unpack .../openssl_3.0.13-0ubuntu1_arm64.deb ... 101s Unpacking openssl (3.0.13-0ubuntu1) over (3.0.10-1ubuntu4) ... 101s Preparing to unpack .../python3-cryptography_41.0.7-4build2_arm64.deb ... 101s Unpacking python3-cryptography (41.0.7-4build2) over (41.0.7-3) ... 101s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 101s wget depends on libssl3 (>= 3.0.0). 101s u-boot-tools depends on libssl3 (>= 3.0.0). 101s tnftp depends on libssl3 (>= 3.0.0). 101s tcpdump depends on libssl3 (>= 3.0.0). 101s systemd-resolved depends on libssl3 (>= 3.0.0). 101s systemd depends on libssl3 (>= 3.0.0). 101s sudo depends on libssl3 (>= 3.0.0). 101s sbsigntool depends on libssl3 (>= 3.0.0). 101s rsync depends on libssl3 (>= 3.0.0). 101s openssh-server depends on libssl3 (>= 3.0.10). 101s openssh-client depends on libssl3 (>= 3.0.10). 101s mtd-utils depends on libssl3 (>= 3.0.0). 101s mokutil depends on libssl3 (>= 3.0.0). 101s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 101s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 101s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 101s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 101s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 101s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 101s libnvme1 depends on libssl3 (>= 3.0.0). 101s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 101s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 101s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 101s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 101s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 101s kmod depends on libssl3 (>= 3.0.0). 101s dhcpcd-base depends on libssl3 (>= 3.0.0). 101s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 101s 101s (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 ... 74662 files and directories currently installed.) 101s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 102s Selecting previously unselected package libssl3t64:arm64. 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 ... 74651 files and directories currently installed.) 102s Preparing to unpack .../0-libssl3t64_3.0.13-0ubuntu1_arm64.deb ... 102s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu1) ... 102s Preparing to unpack .../1-ubuntu-minimal_1.535_arm64.deb ... 102s Unpacking ubuntu-minimal (1.535) over (1.534) ... 102s Selecting previously unselected package libnuma1:arm64. 102s Preparing to unpack .../2-libnuma1_2.0.18-1_arm64.deb ... 102s Unpacking libnuma1:arm64 (2.0.18-1) ... 102s Preparing to unpack .../3-ubuntu-standard_1.535_arm64.deb ... 102s Unpacking ubuntu-standard (1.535) over (1.534) ... 102s Preparing to unpack .../4-efibootmgr_18-1build1_arm64.deb ... 102s Unpacking efibootmgr (18-1build1) over (18-1) ... 102s Selecting previously unselected package libsensors-config. 102s Preparing to unpack .../5-libsensors-config_1%3a3.6.0-9_all.deb ... 102s Unpacking libsensors-config (1:3.6.0-9) ... 102s Selecting previously unselected package libsensors5:arm64. 102s Preparing to unpack .../6-libsensors5_1%3a3.6.0-9_arm64.deb ... 102s Unpacking libsensors5:arm64 (1:3.6.0-9) ... 102s Selecting previously unselected package numactl. 102s Preparing to unpack .../7-numactl_2.0.18-1_arm64.deb ... 102s Unpacking numactl (2.0.18-1) ... 102s Selecting previously unselected package sysstat. 102s Preparing to unpack .../8-sysstat_12.6.1-1ubuntu1_arm64.deb ... 102s Unpacking sysstat (12.6.1-1ubuntu1) ... 102s Setting up efibootmgr (18-1build1) ... 102s Setting up ubuntu-minimal (1.535) ... 102s Setting up libssl3t64:arm64 (3.0.13-0ubuntu1) ... 102s Setting up libsensors-config (1:3.6.0-9) ... 102s Setting up ubuntu-standard (1.535) ... 102s Setting up python3-cryptography (41.0.7-4build2) ... 102s Setting up libsensors5:arm64 (1:3.6.0-9) ... 102s Setting up libnuma1:arm64 (2.0.18-1) ... 102s Setting up openssl (3.0.13-0ubuntu1) ... 102s Setting up sysstat (12.6.1-1ubuntu1) ... 102s 102s Creating config file /etc/default/sysstat with new version 102s update-alternatives: using /usr/bin/sar.sysstat to provide /usr/bin/sar (sar) in auto mode 103s Created symlink /etc/systemd/system/sysstat.service.wants/sysstat-collect.timer → /usr/lib/systemd/system/sysstat-collect.timer. 103s Created symlink /etc/systemd/system/sysstat.service.wants/sysstat-summary.timer → /usr/lib/systemd/system/sysstat-summary.timer. 103s Created symlink /etc/systemd/system/multi-user.target.wants/sysstat.service → /usr/lib/systemd/system/sysstat.service. 104s Setting up numactl (2.0.18-1) ... 104s Processing triggers for man-db (2.12.0-3) ... 105s Processing triggers for libc-bin (2.39-0ubuntu2) ... 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s The following packages will be REMOVED: 106s ubuntu-advantage-tools* 107s 0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded. 107s After this operation, 71.7 kB disk space will be freed. 107s (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 ... 74757 files and directories currently installed.) 107s Removing ubuntu-advantage-tools (31.1) ... 107s (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 ... 74751 files and directories currently installed.) 107s Purging configuration files for ubuntu-advantage-tools (31.1) ... 108s sh: Attempting to set up Debian/Ubuntu apt sources automatically 108s sh: Distribution appears to be Ubuntu 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 109s eatmydata is already the newest version (131-1). 109s dbus is already the newest version (1.14.10-4ubuntu1). 109s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 109s Reading package lists... 109s Building dependency tree... 109s Reading state information... 109s rng-tools-debian is already the newest version (2.4). 109s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 109s Reading package lists... 110s Building dependency tree... 110s Reading state information... 110s haveged is already the newest version (1.9.14-1ubuntu1). 110s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Reading package lists... 110s Building dependency tree... 110s Reading state information... 112s The following packages will be REMOVED: 112s cloud-init* python3-configobj* python3-debconf* 112s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 112s After this operation, 3248 kB disk space will be freed. 112s (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 ... 74750 files and directories currently installed.) 112s Removing cloud-init (24.1-0ubuntu1) ... 112s Removing python3-configobj (5.0.8-3) ... 112s Removing python3-debconf (1.5.86) ... 112s Processing triggers for man-db (2.12.0-3) ... 113s (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 ... 74361 files and directories currently installed.) 113s Purging configuration files for cloud-init (24.1-0ubuntu1) ... 113s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 113s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s linux-generic is already the newest version (6.8.0-11.11+1). 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 116s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 116s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 116s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 117s Reading package lists... 117s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Calculating upgrade... 118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s autopkgtest [16:40:29]: rebooting testbed after setup commands that affected boot 375s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 380s autopkgtest [16:44:50]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 381s autopkgtest [16:44:51]: testbed dpkg architecture: arm64 383s autopkgtest [16:44:53]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 385s Get:1 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (dsc) [2581 B] 385s Get:2 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (tar) [883 kB] 385s Get:3 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (asc) [963 B] 385s Get:4 http://ftpmaster.internal/ubuntu noble/universe stunnel4 3:5.70-2 (diff) [53.2 kB] 387s gpgv: Signature made Sun Sep 10 18:42:44 2023 UTC 387s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 387s gpgv: issuer "roam@debian.org" 387s gpgv: Can't check signature: No public key 387s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.70-2.dsc: no acceptable signature found 387s autopkgtest [16:44:57]: testing package stunnel4 version 3:5.70-2 387s autopkgtest [16:44:57]: build not needed 387s autopkgtest [16:44:57]: test debian-python: preparing testbed 392s Reading package lists... 392s Building dependency tree... 392s Reading state information... 393s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 393s Starting 2 pkgProblemResolver with broken count: 0 393s Done 394s Done 395s Starting pkgProblemResolver with broken count: 0 395s Starting 2 pkgProblemResolver with broken count: 0 395s Done 396s The following additional packages will be installed: 396s python3-all python3-distutils python3-lib2to3 stunnel4 396s Suggested packages: 396s logcheck-database 396s The following NEW packages will be installed: 396s python3-all python3-distutils python3-lib2to3 stunnel4 397s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 397s 1 not fully installed or removed. 397s Need to get 425 kB of archives. 397s After this operation, 1692 kB of additional disk space will be used. 397s Get:1 http://ftpmaster.internal/ubuntu noble/universe arm64 stunnel4 arm64 3:5.70-2 [215 kB] 397s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 python3-lib2to3 all 3.11.5-1 [79.0 kB] 397s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 python3-distutils all 3.11.5-1 [131 kB] 397s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 python3-all arm64 3.12.1-0ubuntu2 [906 B] 398s Fetched 425 kB in 1s (792 kB/s) 398s Selecting previously unselected package stunnel4. 398s (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 ... 74306 files and directories currently installed.) 398s Preparing to unpack .../stunnel4_3%3a5.70-2_arm64.deb ... 398s Unpacking stunnel4 (3:5.70-2) ... 398s Selecting previously unselected package python3-lib2to3. 398s Preparing to unpack .../python3-lib2to3_3.11.5-1_all.deb ... 398s Unpacking python3-lib2to3 (3.11.5-1) ... 398s Selecting previously unselected package python3-distutils. 398s Preparing to unpack .../python3-distutils_3.11.5-1_all.deb ... 398s Unpacking python3-distutils (3.11.5-1) ... 398s Selecting previously unselected package python3-all. 398s Preparing to unpack .../python3-all_3.12.1-0ubuntu2_arm64.deb ... 398s Unpacking python3-all (3.12.1-0ubuntu2) ... 398s Setting up stunnel4 (3:5.70-2) ... 398s Creating group 'stunnel4' with GID 992. 398s Creating user 'stunnel4' (stunnel service system account) with UID 992 and GID 992. 399s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 399s Setting up python3-lib2to3 (3.11.5-1) ... 400s Setting up python3-distutils (3.11.5-1) ... 400s python3.12: can't get files for byte-compilation 400s Setting up python3-all (3.12.1-0ubuntu2) ... 400s Setting up autopkgtest-satdep (0) ... 400s Processing triggers for man-db (2.12.0-3) ... 406s (Reading database ... 74567 files and directories currently installed.) 406s Removing autopkgtest-satdep (0) ... 407s autopkgtest [16:45:17]: 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 407s autopkgtest [16:45:17]: test debian-python: [----------------------- 408s 408s 408s ====== python3.12 408s 408s Using /tmp/struntime.2zmm2e6l as a temporary directory 408s Trying to obtain the version of /usr/bin/stunnel4 408s Started `/usr/bin/stunnel4 -version` as process 1261 408s Got stunnel version 5.70 408s [main] Starting to do things 408s [main] Awaiting the 'listener started' event 408s [find_listening_port] Looking for a port to listen on 408s [find_listening_port] Trying localhost port 6502 408s [listener] Telling the main thread about [127.0.0.1]:6502 408s [listener] Awaiting client connections... 408s [main] Apparently we are listening on [127.0.0.1]:6502 408s [main] Testing cleartext connections 408s [test_connect [127.0.0.1]:6502 0] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 1] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 2] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 3] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 4] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 5] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 6] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 7] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 8] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 9] Trying port 6502 encrypted False 408s [test_connect [127.0.0.1]:6502 7] Opening an unencrypted connection 408s [listener] A client connected from somewhere 408s [[127.0.0.1]:52806] New connection 408s [[127.0.0.1]:52806] Telling the main thread 408s [[127.0.0.1]:52806] Let them tell us something... 408s [test_connect [127.0.0.1]:6502 7] Connected to the server: [127.0.0.1]:52806 409s [test_connect [127.0.0.1]:6502 6] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52816] New connection 409s [[127.0.0.1]:52816] Telling the main thread 409s [[127.0.0.1]:52816] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 6] Connected to the server: [127.0.0.1]:52816 409s [test_connect [127.0.0.1]:6502 0] Opening an unencrypted connection 409s [test_connect [127.0.0.1]:6502 2] Opening an unencrypted connection 409s [test_connect [127.0.0.1]:6502 4] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52830] New connection 409s [[127.0.0.1]:52830] Telling the main thread 409s [[127.0.0.1]:52830] Let them tell us something... 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52832] New connection 409s [[127.0.0.1]:52832] Telling the main thread 409s [[127.0.0.1]:52832] Let them tell us something... 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52848] New connection 409s [[127.0.0.1]:52848] Telling the main thread 409s [[127.0.0.1]:52848] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 0] Connected to the server: [127.0.0.1]:52830 409s [test_connect [127.0.0.1]:6502 2] Connected to the server: [127.0.0.1]:52832 409s [test_connect [127.0.0.1]:6502 4] Connected to the server: [127.0.0.1]:52848 409s [test_connect [127.0.0.1]:6502 3] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52852] New connection 409s [[127.0.0.1]:52852] Telling the main thread 409s [[127.0.0.1]:52852] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 3] Connected to the server: [127.0.0.1]:52852 409s [test_connect [127.0.0.1]:6502 1] Opening an unencrypted connection 409s [test_connect [127.0.0.1]:6502 8] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52862] New connection 409s [[127.0.0.1]:52862] Telling the main thread 409s [[127.0.0.1]:52862] Let them tell us something... 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52878] New connection 409s [[127.0.0.1]:52878] Telling the main thread 409s [[127.0.0.1]:52878] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 1] Connected to the server: [127.0.0.1]:52862 409s [test_connect [127.0.0.1]:6502 8] Connected to the server: [127.0.0.1]:52878 409s [test_connect [127.0.0.1]:6502 6] Sending something... 409s [test_connect [127.0.0.1]:6502 6] Waiting for the main thread to figure it out 409s [[127.0.0.1]:52816] Telling the main thread about b'Hello 6\n' 409s [[127.0.0.1]:52816] Writing something back... 409s [[127.0.0.1]:52816] Telling the main thread about b'There!\n' 409s [[127.0.0.1]:52816] Closing down the writer socket 409s [test_connect [127.0.0.1]:6502 6] The listener acknowledged [127.0.0.1]:52816 409s [test_connect [127.0.0.1]:6502 6] Waiting for the server to receive it... 409s [test_connect [127.0.0.1]:6502 6] Waiting for the server to send something 409s [test_connect [127.0.0.1]:6502 6] Trying to receive the actual data 409s [test_connect [127.0.0.1]:6502 6] Waiting for the server to close the connection 409s [[127.0.0.1]:52816] Telling the main thread done 409s [test_connect [127.0.0.1]:6502 6] Waiting for an EOF on the reader socket 409s [test_connect [127.0.0.1]:6502 6] Closing our writer socket, too 409s [test_connect [127.0.0.1]:6502 6] Letting the main thread know we're done 409s [test_connect [127.0.0.1]:6502 6] Done 409s [process_connections] Fetching task plain-6 409s [process_connections] Waiting for task plain-6 409s [process_connections] Done with task plain-6 409s [test_connect [127.0.0.1]:6502 5] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52884] New connection 409s [[127.0.0.1]:52884] Telling the main thread 409s [[127.0.0.1]:52884] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 5] Connected to the server: [127.0.0.1]:52884 409s [test_connect [127.0.0.1]:6502 9] Opening an unencrypted connection 409s [listener] A client connected from somewhere 409s [[127.0.0.1]:52890] New connection 409s [[127.0.0.1]:52890] Telling the main thread 409s [[127.0.0.1]:52890] Let them tell us something... 409s [test_connect [127.0.0.1]:6502 9] Connected to the server: [127.0.0.1]:52890 409s [test_connect [127.0.0.1]:6502 3] Sending something... 409s [test_connect [127.0.0.1]:6502 3] Waiting for the main thread to figure it out 409s [[127.0.0.1]:52852] Telling the main thread about b'Hello 3\n' 409s [[127.0.0.1]:52852] Writing something back... 409s [[127.0.0.1]:52852] Telling the main thread about b'There!\n' 409s [[127.0.0.1]:52852] Closing down the writer socket 409s [test_connect [127.0.0.1]:6502 3] The listener acknowledged [127.0.0.1]:52852 409s [test_connect [127.0.0.1]:6502 3] Waiting for the server to receive it... 409s [test_connect [127.0.0.1]:6502 3] Waiting for the server to send something 409s [test_connect [127.0.0.1]:6502 3] Trying to receive the actual data 409s [test_connect [127.0.0.1]:6502 3] Waiting for the server to close the connection 409s [[127.0.0.1]:52852] Telling the main thread done 409s [test_connect [127.0.0.1]:6502 3] Waiting for an EOF on the reader socket 409s [test_connect [127.0.0.1]:6502 3] Closing our writer socket, too 409s [test_connect [127.0.0.1]:6502 3] Letting the main thread know we're done 409s [test_connect [127.0.0.1]:6502 3] Done 409s [process_connections] Fetching task plain-3 409s [process_connections] Waiting for task plain-3 409s [process_connections] Done with task plain-3 409s [test_connect [127.0.0.1]:6502 7] Sending something... 409s [test_connect [127.0.0.1]:6502 7] Waiting for the main thread to figure it out 409s [[127.0.0.1]:52806] Telling the main thread about b'Hello 7\n' 409s [[127.0.0.1]:52806] Writing something back... 409s [[127.0.0.1]:52806] Telling the main thread about b'There!\n' 409s [[127.0.0.1]:52806] Closing down the writer socket 409s [test_connect [127.0.0.1]:6502 7] The listener acknowledged [127.0.0.1]:52806 409s [test_connect [127.0.0.1]:6502 7] Waiting for the server to receive it... 409s [test_connect [127.0.0.1]:6502 7] Waiting for the server to send something 409s [test_connect [127.0.0.1]:6502 7] Trying to receive the actual data 409s [test_connect [127.0.0.1]:6502 7] Waiting for the server to close the connection 409s [[127.0.0.1]:52806] Telling the main thread done 409s [test_connect [127.0.0.1]:6502 7] Waiting for an EOF on the reader socket 409s [test_connect [127.0.0.1]:6502 7] Closing our writer socket, too 409s [test_connect [127.0.0.1]:6502 7] Letting the main thread know we're done 409s [test_connect [127.0.0.1]:6502 7] Done 409s [process_connections] Fetching task plain-7 409s [process_connections] Waiting for task plain-7 409s [process_connections] Done with task plain-7 410s [test_connect [127.0.0.1]:6502 1] Sending something... 410s [test_connect [127.0.0.1]:6502 1] Waiting for the main thread to figure it out 410s [[127.0.0.1]:52862] Telling the main thread about b'Hello 1\n' 410s [[127.0.0.1]:52862] Writing something back... 410s [[127.0.0.1]:52862] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52862] Closing down the writer socket 410s [test_connect [127.0.0.1]:6502 1] The listener acknowledged [127.0.0.1]:52862 410s [test_connect [127.0.0.1]:6502 1] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 1] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 1] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 1] Waiting for the server to close the connection 410s [[127.0.0.1]:52862] Telling the main thread done 410s [test_connect [127.0.0.1]:6502 1] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 1] Closing our writer socket, too 410s [test_connect [127.0.0.1]:6502 1] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 1] Done 410s [process_connections] Fetching task plain-1 410s [process_connections] Waiting for task plain-1 410s [process_connections] Done with task plain-1 410s [test_connect [127.0.0.1]:6502 9] Sending something... 410s [test_connect [127.0.0.1]:6502 9] Waiting for the main thread to figure it out 410s [test_connect [127.0.0.1]:6502 8] Sending something... 410s [test_connect [127.0.0.1]:6502 8] Waiting for the main thread to figure it out 410s [[127.0.0.1]:52890] Telling the main thread about b'Hello 9\n' 410s [[127.0.0.1]:52890] Writing something back... 410s [[127.0.0.1]:52890] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52890] Closing down the writer socket 410s [[127.0.0.1]:52878] Telling the main thread about b'Hello 8\n' 410s [[127.0.0.1]:52878] Writing something back... 410s [[127.0.0.1]:52878] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52878] Closing down the writer socket 410s [test_connect [127.0.0.1]:6502 0] Sending something... 410s [test_connect [127.0.0.1]:6502 0] Waiting for the main thread to figure it out 410s [test_connect [127.0.0.1]:6502 9] The listener acknowledged [127.0.0.1]:52890 410s [test_connect [127.0.0.1]:6502 9] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 9] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 9] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 9] Waiting for the server to close the connection 410s [test_connect [127.0.0.1]:6502 8] The listener acknowledged [127.0.0.1]:52878 410s [test_connect [127.0.0.1]:6502 8] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 8] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 8] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 8] Waiting for the server to close the connection 410s [[127.0.0.1]:52890] Telling the main thread done 410s [[127.0.0.1]:52878] Telling the main thread done 410s [test_connect [127.0.0.1]:6502 2] Sending something... 410s [test_connect [127.0.0.1]:6502 2] Waiting for the main thread to figure it out 410s [test_connect [127.0.0.1]:6502 4] Sending something... 410s [test_connect [127.0.0.1]:6502 4] Waiting for the main thread to figure it out 410s [[127.0.0.1]:52830] Telling the main thread about b'Hello 0\n' 410s [[127.0.0.1]:52830] Writing something back... 410s [[127.0.0.1]:52830] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52830] Closing down the writer socket 410s [test_connect [127.0.0.1]:6502 9] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 9] Closing our writer socket, too 410s [test_connect [127.0.0.1]:6502 8] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 8] Closing our writer socket, too 410s [[127.0.0.1]:52832] Telling the main thread about b'Hello 2\n' 410s [[127.0.0.1]:52832] Writing something back... 410s [[127.0.0.1]:52832] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52832] Closing down the writer socket 410s [[127.0.0.1]:52848] Telling the main thread about b'Hello 4\n' 410s [[127.0.0.1]:52848] Writing something back... 410s [[127.0.0.1]:52848] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52848] Closing down the writer socket 410s [test_connect [127.0.0.1]:6502 0] The listener acknowledged [127.0.0.1]:52830 410s [test_connect [127.0.0.1]:6502 0] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 0] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 0] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 0] Waiting for the server to close the connection 410s [[127.0.0.1]:52830] Telling the main thread done 410s [test_connect [127.0.0.1]:6502 9] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 9] Done 410s [test_connect [127.0.0.1]:6502 8] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 8] Done 410s [test_connect [127.0.0.1]:6502 2] The listener acknowledged [127.0.0.1]:52832 410s [test_connect [127.0.0.1]:6502 2] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 2] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 2] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 2] Waiting for the server to close the connection 410s [test_connect [127.0.0.1]:6502 4] The listener acknowledged [127.0.0.1]:52848 410s [test_connect [127.0.0.1]:6502 4] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 4] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 4] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 4] Waiting for the server to close the connection 410s [test_connect [127.0.0.1]:6502 0] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 0] Closing our writer socket, too 410s [[127.0.0.1]:52832] Telling the main thread done 410s [[127.0.0.1]:52848] Telling the main thread done 410s [process_connections] Fetching task plain-9 410s [process_connections] Waiting for task plain-9 410s [process_connections] Done with task plain-9 410s [process_connections] Fetching task plain-8 410s [process_connections] Waiting for task plain-8 410s [process_connections] Done with task plain-8 410s [test_connect [127.0.0.1]:6502 2] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 2] Closing our writer socket, too 410s [test_connect [127.0.0.1]:6502 4] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 4] Closing our writer socket, too 410s [test_connect [127.0.0.1]:6502 0] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 0] Done 410s [process_connections] Fetching task plain-0 410s [process_connections] Waiting for task plain-0 410s [process_connections] Done with task plain-0 410s [test_connect [127.0.0.1]:6502 2] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 2] Done 410s [test_connect [127.0.0.1]:6502 4] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 4] Done 410s [process_connections] Fetching task plain-2 410s [process_connections] Waiting for task plain-2 410s [process_connections] Done with task plain-2 410s [process_connections] Fetching task plain-4 410s [process_connections] Waiting for task plain-4 410s [process_connections] Done with task plain-4 410s [test_connect [127.0.0.1]:6502 5] Sending something... 410s [test_connect [127.0.0.1]:6502 5] Waiting for the main thread to figure it out 410s [[127.0.0.1]:52884] Telling the main thread about b'Hello 5\n' 410s [[127.0.0.1]:52884] Writing something back... 410s [[127.0.0.1]:52884] Telling the main thread about b'There!\n' 410s [[127.0.0.1]:52884] Closing down the writer socket 410s [test_connect [127.0.0.1]:6502 5] The listener acknowledged [127.0.0.1]:52884 410s [test_connect [127.0.0.1]:6502 5] Waiting for the server to receive it... 410s [test_connect [127.0.0.1]:6502 5] Waiting for the server to send something 410s [test_connect [127.0.0.1]:6502 5] Trying to receive the actual data 410s [test_connect [127.0.0.1]:6502 5] Waiting for the server to close the connection 410s [[127.0.0.1]:52884] Telling the main thread done 410s [test_connect [127.0.0.1]:6502 5] Waiting for an EOF on the reader socket 410s [test_connect [127.0.0.1]:6502 5] Closing our writer socket, too 410s [test_connect [127.0.0.1]:6502 5] Letting the main thread know we're done 410s [test_connect [127.0.0.1]:6502 5] Done 410s [process_connections] Fetching task plain-5 410s [process_connections] Waiting for task plain-5 410s [process_connections] Done with task plain-5 410s [main] Picking a listen address:port for stunnel 410s [find_listening_port] Looking for a port to listen on 410s [find_listening_port] Trying 127.0.0.1 port 6503 410s [find_listening_port] got 6503, shutting down the listener 410s [main] Will put stunnel at 127.0.0.1:6503 410s [main] Preparing the stunnel config file 410s [main] Using config file /tmp/struntime.2zmm2e6l/stunnel.conf 410s [main] 410s [main] pid = /tmp/struntime.2zmm2e6l/stunnel.pid 410s [main] foreground = yes 410s [main] 410s [main] cert = /tmp/autopkgtest.oCcs5T/build.Hu0/src/debian/tests/certs/certificate.pem 410s [main] key = /tmp/struntime.2zmm2e6l/key.pem 410s [main] 410s [main] [test] 410s [main] accept = 127.0.0.1:6503 410s [main] connect = 127.0.0.1:6502 410s [main] End of the config file 410s [main] Launching the stunnel server 410s [main] - got pid 1265 410s [main] Launching the stunnel output pipe thread 410s [main] Waiting for stunnel to start up 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: stunnel 5.70 on aarch64-unknown-linux-gnu platform' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: Compiled with OpenSSL 3.0.10 1 Aug 2023' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: Running with OpenSSL 3.0.13 30 Jan 2024' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: Reading configuration from file /tmp/struntime.2zmm2e6l/stunnel.conf' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: UTF-8 byte order mark not detected' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: FIPS mode disabled' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[ui]: Configuration successful' 410s [stunnel_output] The main thread ought to know 410s [main] Testing the encrypted connections 410s [test_connect [127.0.0.1]:6503 0] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 1] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 2] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 3] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 4] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 5] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 6] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 7] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 8] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 9] Trying port 6503 encrypted True 410s [test_connect [127.0.0.1]:6503 8] Creating an SSL context 410s [test_connect [127.0.0.1]:6503 8] - cert required 410s [test_connect [127.0.0.1]:6503 8] - load_verify_locations() 410s [test_connect [127.0.0.1]:6503 8] Opening an SSL connection 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[0]: Service [test] accepted connection from 127.0.0.1:38176' 410s [test_connect [127.0.0.1]:6503 8] Connected to the server: [127.0.0.1]:38176 410s [listener] A client connected from somewhere 410s [[127.0.0.1]:52902] New connection 410s [[127.0.0.1]:52902] Telling the main thread 410s [[127.0.0.1]:52902] Let them tell us something... 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[0]: s_connect: connected 127.0.0.1:6502' 410s [stunnel_output] Read '2024.03.09 16:45:20 LOG5[0]: Service [test] connected remote server from 127.0.0.1:52902' 411s [test_connect [127.0.0.1]:6503 0] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 0] - cert required 411s [test_connect [127.0.0.1]:6503 0] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 0] Opening an SSL connection 411s [test_connect [127.0.0.1]:6503 2] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 2] - cert required 411s [test_connect [127.0.0.1]:6503 2] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 2] Opening an SSL connection 411s [test_connect [127.0.0.1]:6503 4] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 4] - cert required 411s [test_connect [127.0.0.1]:6503 4] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 4] Opening an SSL connection 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[1]: Service [test] accepted connection from 127.0.0.1:38188' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[2]: Service [test] accepted connection from 127.0.0.1:38190' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[3]: Service [test] accepted connection from 127.0.0.1:38206' 411s [test_connect [127.0.0.1]:6503 2] Connected to the server: [127.0.0.1]:38190 411s [test_connect [127.0.0.1]:6503 4] Connected to the server: [127.0.0.1]:38206 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[2]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[2]: Service [test] connected remote server from 127.0.0.1:52906' 411s [test_connect [127.0.0.1]:6503 0] Connected to the server: [127.0.0.1]:38188 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52906] New connection 411s [[127.0.0.1]:52906] Telling the main thread 411s [[127.0.0.1]:52906] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[3]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[3]: Service [test] connected remote server from 127.0.0.1:52912' 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52912] New connection 411s [[127.0.0.1]:52912] Telling the main thread 411s [[127.0.0.1]:52912] Let them tell us something... 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52918] New connection 411s [[127.0.0.1]:52918] Telling the main thread 411s [[127.0.0.1]:52918] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[1]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[1]: Service [test] connected remote server from 127.0.0.1:52918' 411s [test_connect [127.0.0.1]:6503 6] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 6] - cert required 411s [test_connect [127.0.0.1]:6503 6] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 6] Opening an SSL connection 411s [test_connect [127.0.0.1]:6503 7] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 7] - cert required 411s [test_connect [127.0.0.1]:6503 7] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 7] Opening an SSL connection 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[4]: Service [test] accepted connection from 127.0.0.1:38212' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[5]: Service [test] accepted connection from 127.0.0.1:38226' 411s [test_connect [127.0.0.1]:6503 6] Connected to the server: [127.0.0.1]:38212 411s [test_connect [127.0.0.1]:6503 7] Connected to the server: [127.0.0.1]:38226 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[4]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[4]: Service [test] connected remote server from 127.0.0.1:52920' 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52920] New connection 411s [[127.0.0.1]:52920] Telling the main thread 411s [[127.0.0.1]:52920] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[5]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[5]: Service [test] connected remote server from 127.0.0.1:52930' 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52930] New connection 411s [[127.0.0.1]:52930] Telling the main thread 411s [[127.0.0.1]:52930] Let them tell us something... 411s [test_connect [127.0.0.1]:6503 8] Sending something... 411s [test_connect [127.0.0.1]:6503 8] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52902] Telling the main thread about b'Hello 8\n' 411s [[127.0.0.1]:52902] Writing something back... 411s [[127.0.0.1]:52902] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52902] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 8] The listener acknowledged [127.0.0.1]:52902 411s [test_connect [127.0.0.1]:6503 8] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 8] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 8] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 8] Waiting for the server to close the connection 411s [[127.0.0.1]:52902] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 8] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 8] Closing our writer socket, too 411s [test_connect [127.0.0.1]:6503 8] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 8] Done 411s [process_connections] Fetching task encr-8 411s [process_connections] Waiting for task encr-8 411s [process_connections] Done with task encr-8 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 1] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 1] - cert required 411s [test_connect [127.0.0.1]:6503 1] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 1] Opening an SSL connection 411s [test_connect [127.0.0.1]:6503 5] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 5] - cert required 411s [test_connect [127.0.0.1]:6503 5] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 5] Opening an SSL connection 411s [test_connect [127.0.0.1]:6503 9] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 9] - cert required 411s [test_connect [127.0.0.1]:6503 9] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 9] Opening an SSL connection 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[6]: Service [test] accepted connection from 127.0.0.1:38240' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[7]: Service [test] accepted connection from 127.0.0.1:38250' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[8]: Service [test] accepted connection from 127.0.0.1:38262' 411s [test_connect [127.0.0.1]:6503 5] Connected to the server: [127.0.0.1]:38250 411s [test_connect [127.0.0.1]:6503 9] Connected to the server: [127.0.0.1]:38262 411s [test_connect [127.0.0.1]:6503 1] Connected to the server: [127.0.0.1]:38240 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[7]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[7]: Service [test] connected remote server from 127.0.0.1:52940' 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52940] New connection 411s [[127.0.0.1]:52940] Telling the main thread 411s [[127.0.0.1]:52940] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[8]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[8]: Service [test] connected remote server from 127.0.0.1:52950' 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52950] New connection 411s [[127.0.0.1]:52950] Telling the main thread 411s [[127.0.0.1]:52950] Let them tell us something... 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52964] New connection 411s [[127.0.0.1]:52964] Telling the main thread 411s [[127.0.0.1]:52964] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[6]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[6]: Service [test] connected remote server from 127.0.0.1:52964' 411s [test_connect [127.0.0.1]:6503 3] Creating an SSL context 411s [test_connect [127.0.0.1]:6503 3] - cert required 411s [test_connect [127.0.0.1]:6503 3] - load_verify_locations() 411s [test_connect [127.0.0.1]:6503 3] Opening an SSL connection 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[9]: Service [test] accepted connection from 127.0.0.1:38272' 411s [test_connect [127.0.0.1]:6503 3] Connected to the server: [127.0.0.1]:38272 411s [listener] A client connected from somewhere 411s [[127.0.0.1]:52974] New connection 411s [[127.0.0.1]:52974] Telling the main thread 411s [[127.0.0.1]:52974] Let them tell us something... 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[9]: s_connect: connected 127.0.0.1:6502' 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[9]: Service [test] connected remote server from 127.0.0.1:52974' 411s [test_connect [127.0.0.1]:6503 2] Sending something... 411s [test_connect [127.0.0.1]:6503 2] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52906] Telling the main thread about b'Hello 2\n' 411s [[127.0.0.1]:52906] Writing something back... 411s [[127.0.0.1]:52906] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52906] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 2] The listener acknowledged [127.0.0.1]:52906 411s [test_connect [127.0.0.1]:6503 2] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 2] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 2] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 2] Waiting for the server to close the connection 411s [[127.0.0.1]:52906] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 4] Sending something... 411s [test_connect [127.0.0.1]:6503 4] Waiting for the main thread to figure it out 411s [test_connect [127.0.0.1]:6503 2] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 2] Closing our writer socket, too 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 2] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 2] Done 411s [[127.0.0.1]:52912] Telling the main thread about b'Hello 4\n' 411s [[127.0.0.1]:52912] Writing something back... 411s [[127.0.0.1]:52912] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52912] Closing down the writer socket 411s [process_connections] Fetching task encr-2 411s [process_connections] Waiting for task encr-2 411s [process_connections] Done with task encr-2 411s [test_connect [127.0.0.1]:6503 4] The listener acknowledged [127.0.0.1]:52912 411s [test_connect [127.0.0.1]:6503 4] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 4] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 4] Trying to receive the actual data 411s [[127.0.0.1]:52912] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 4] Waiting for the server to close the connection 411s [test_connect [127.0.0.1]:6503 4] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 4] Closing our writer socket, too 411s [test_connect [127.0.0.1]:6503 4] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 4] Done 411s [process_connections] Fetching task encr-4 411s [process_connections] Waiting for task encr-4 411s [process_connections] Done with task encr-4 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 7] Sending something... 411s [test_connect [127.0.0.1]:6503 7] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52930] Telling the main thread about b'Hello 7\n' 411s [[127.0.0.1]:52930] Writing something back... 411s [[127.0.0.1]:52930] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52930] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 7] The listener acknowledged [127.0.0.1]:52930 411s [test_connect [127.0.0.1]:6503 7] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 7] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 7] Trying to receive the actual data 411s [[127.0.0.1]:52930] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 7] Waiting for the server to close the connection 411s [test_connect [127.0.0.1]:6503 7] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 7] Closing our writer socket, too 411s [test_connect [127.0.0.1]:6503 7] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 7] Done 411s [process_connections] Fetching task encr-7 411s [process_connections] Waiting for task encr-7 411s [process_connections] Done with task encr-7 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 0] Sending something... 411s [test_connect [127.0.0.1]:6503 0] Waiting for the main thread to figure it out 411s [test_connect [127.0.0.1]:6503 1] Sending something... 411s [test_connect [127.0.0.1]:6503 1] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52918] Telling the main thread about b'Hello 0\n' 411s [[127.0.0.1]:52918] Writing something back... 411s [[127.0.0.1]:52918] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52918] Closing down the writer socket 411s [[127.0.0.1]:52964] Telling the main thread about b'Hello 1\n' 411s [[127.0.0.1]:52964] Writing something back... 411s [[127.0.0.1]:52964] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52964] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 0] The listener acknowledged [127.0.0.1]:52918 411s [test_connect [127.0.0.1]:6503 0] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 0] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 0] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 0] Waiting for the server to close the connection 411s [[127.0.0.1]:52918] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 1] The listener acknowledged [127.0.0.1]:52964 411s [test_connect [127.0.0.1]:6503 1] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 1] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 1] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 1] Waiting for the server to close the connection 411s [test_connect [127.0.0.1]:6503 0] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 0] Closing our writer socket, too 411s [[127.0.0.1]:52964] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 1] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 1] Closing our writer socket, too 411s [test_connect [127.0.0.1]:6503 0] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 0] Done 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [process_connections] Fetching task encr-0 411s [process_connections] Waiting for task encr-0 411s [process_connections] Done with task encr-0 411s [test_connect [127.0.0.1]:6503 1] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 1] Done 411s [process_connections] Fetching task encr-1 411s [process_connections] Waiting for task encr-1 411s [process_connections] Done with task encr-1 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 3] Sending something... 411s [test_connect [127.0.0.1]:6503 3] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52974] Telling the main thread about b'Hello 3\n' 411s [[127.0.0.1]:52974] Writing something back... 411s [[127.0.0.1]:52974] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52974] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 3] The listener acknowledged [127.0.0.1]:52974 411s [test_connect [127.0.0.1]:6503 3] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 3] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 3] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 3] Waiting for the server to close the connection 411s [[127.0.0.1]:52974] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 3] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 3] Closing our writer socket, too 411s [test_connect [127.0.0.1]:6503 3] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 3] Done 411s [process_connections] Fetching task encr-3 411s [process_connections] Waiting for task encr-3 411s [process_connections] Done with task encr-3 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 5] Sending something... 411s [test_connect [127.0.0.1]:6503 5] Waiting for the main thread to figure it out 411s [[127.0.0.1]:52940] Telling the main thread about b'Hello 5\n' 411s [[127.0.0.1]:52940] Writing something back... 411s [[127.0.0.1]:52940] Telling the main thread about b'There!\n' 411s [[127.0.0.1]:52940] Closing down the writer socket 411s [test_connect [127.0.0.1]:6503 5] The listener acknowledged [127.0.0.1]:52940 411s [test_connect [127.0.0.1]:6503 5] Waiting for the server to receive it... 411s [test_connect [127.0.0.1]:6503 5] Waiting for the server to send something 411s [test_connect [127.0.0.1]:6503 5] Trying to receive the actual data 411s [test_connect [127.0.0.1]:6503 5] Waiting for the server to close the connection 411s [[127.0.0.1]:52940] Telling the main thread done 411s [test_connect [127.0.0.1]:6503 5] Waiting for an EOF on the reader socket 411s [test_connect [127.0.0.1]:6503 5] Closing our writer socket, too 411s [stunnel_output] Read '2024.03.09 16:45:21 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 411s [test_connect [127.0.0.1]:6503 5] Letting the main thread know we're done 411s [test_connect [127.0.0.1]:6503 5] Done 411s [process_connections] Fetching task encr-5 411s [process_connections] Waiting for task encr-5 411s [process_connections] Done with task encr-5 412s [test_connect [127.0.0.1]:6503 6] Sending something... 412s [test_connect [127.0.0.1]:6503 6] Waiting for the main thread to figure it out 412s [[127.0.0.1]:52920] Telling the main thread about b'Hello 6\n' 412s [[127.0.0.1]:52920] Writing something back... 412s [[127.0.0.1]:52920] Telling the main thread about b'There!\n' 412s [[127.0.0.1]:52920] Closing down the writer socket 412s [test_connect [127.0.0.1]:6503 6] The listener acknowledged [127.0.0.1]:52920 412s [test_connect [127.0.0.1]:6503 6] Waiting for the server to receive it... 412s [test_connect [127.0.0.1]:6503 6] Waiting for the server to send something 412s [test_connect [127.0.0.1]:6503 6] Trying to receive the actual data 412s [test_connect [127.0.0.1]:6503 6] Waiting for the server to close the connection 412s [[127.0.0.1]:52920] Telling the main thread done 412s [test_connect [127.0.0.1]:6503 6] Waiting for an EOF on the reader socket 412s [test_connect [127.0.0.1]:6503 6] Closing our writer socket, too 412s [test_connect [127.0.0.1]:6503 6] Letting the main thread know we're done 412s [test_connect [127.0.0.1]:6503 6] Done 412s [process_connections] Fetching task encr-6 412s [process_connections] Waiting for task encr-6 412s [process_connections] Done with task encr-6 412s [stunnel_output] Read '2024.03.09 16:45:22 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 412s [test_connect [127.0.0.1]:6503 9] Sending something... 412s [test_connect [127.0.0.1]:6503 9] Waiting for the main thread to figure it out 412s [[127.0.0.1]:52950] Telling the main thread about b'Hello 9\n' 412s [[127.0.0.1]:52950] Writing something back... 412s [[127.0.0.1]:52950] Telling the main thread about b'There!\n' 412s [[127.0.0.1]:52950] Closing down the writer socket 412s [test_connect [127.0.0.1]:6503 9] The listener acknowledged [127.0.0.1]:52950 412s [test_connect [127.0.0.1]:6503 9] Waiting for the server to receive it... 412s [test_connect [127.0.0.1]:6503 9] Waiting for the server to send something 412s [test_connect [127.0.0.1]:6503 9] Trying to receive the actual data 412s [test_connect [127.0.0.1]:6503 9] Waiting for the server to close the connection 412s [[127.0.0.1]:52950] Telling the main thread done 412s [test_connect [127.0.0.1]:6503 9] Waiting for an EOF on the reader socket 412s [test_connect [127.0.0.1]:6503 9] Closing our writer socket, too 412s [test_connect [127.0.0.1]:6503 9] Letting the main thread know we're done 412s [test_connect [127.0.0.1]:6503 9] Done 412s [process_connections] Fetching task encr-9 412s [process_connections] Waiting for task encr-9 412s [process_connections] Done with task encr-9 412s [main] Everything seems to be all right! 412s About to cancel 2 remaining task(s) 412s - listen 412s - output 412s Waiting for the tasks to hopefully finish 412s About to kill and wait for 1 child process(es) 412s - pid 1265 412s Waiting for the processes to exit... 412s Got processes' exit status: [-9] 412s autopkgtest [16:45:22]: test debian-python: -----------------------] 413s autopkgtest [16:45:23]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 413s debian-python PASS 413s autopkgtest [16:45:23]: test upstream: preparing testbed 495s autopkgtest [16:46:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 496s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 496s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [429 kB] 497s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [2635 kB] 497s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [37.3 kB] 497s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [3976 B] 497s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [577 kB] 497s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 497s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [20.3 kB] 497s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 497s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [2881 kB] 497s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 497s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [39.2 kB] 497s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 499s Fetched 6752 kB in 2s (2991 kB/s) 499s Reading package lists... 502s Reading package lists... 503s Building dependency tree... 503s Reading state information... 504s Calculating upgrade... 505s The following package was automatically installed and is no longer required: 505s ubuntu-advantage-tools 505s Use 'sudo apt autoremove' to remove it. 505s The following packages will be REMOVED: 505s libssl3 505s The following NEW packages will be installed: 505s libnuma1 libsensors-config libsensors5 libssl3t64 numactl sysstat 505s The following packages will be upgraded: 505s efibootmgr openssl python3-cryptography ubuntu-minimal ubuntu-standard 505s 5 upgraded, 6 newly installed, 1 to remove and 0 not upgraded. 505s Need to get 4154 kB of archives. 505s After this operation, 2289 kB of additional disk space will be used. 505s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 openssl arm64 3.0.13-0ubuntu1 [983 kB] 506s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 python3-cryptography arm64 41.0.7-4build2 [772 kB] 506s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libssl3t64 arm64 3.0.13-0ubuntu1 [1770 kB] 506s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-minimal arm64 1.535 [10.5 kB] 506s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libnuma1 arm64 2.0.18-1 [23.5 kB] 506s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 ubuntu-standard arm64 1.535 [10.5 kB] 506s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 efibootmgr arm64 18-1build1 [31.5 kB] 506s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 libsensors-config all 1:3.6.0-9 [5458 B] 506s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 libsensors5 arm64 1:3.6.0-9 [26.9 kB] 506s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 numactl arm64 2.0.18-1 [39.5 kB] 506s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 sysstat arm64 12.6.1-1ubuntu1 [480 kB] 507s Preconfiguring packages ... 507s Fetched 4154 kB in 1s (4512 kB/s) 508s (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 ... 74662 files and directories currently installed.) 508s Preparing to unpack .../openssl_3.0.13-0ubuntu1_arm64.deb ... 508s Unpacking openssl (3.0.13-0ubuntu1) over (3.0.10-1ubuntu4) ... 508s Preparing to unpack .../python3-cryptography_41.0.7-4build2_arm64.deb ... 508s Unpacking python3-cryptography (41.0.7-4build2) over (41.0.7-3) ... 508s dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: 508s wget depends on libssl3 (>= 3.0.0). 508s u-boot-tools depends on libssl3 (>= 3.0.0). 508s tnftp depends on libssl3 (>= 3.0.0). 508s tcpdump depends on libssl3 (>= 3.0.0). 508s systemd-resolved depends on libssl3 (>= 3.0.0). 508s systemd depends on libssl3 (>= 3.0.0). 508s sudo depends on libssl3 (>= 3.0.0). 508s sbsigntool depends on libssl3 (>= 3.0.0). 508s rsync depends on libssl3 (>= 3.0.0). 508s openssh-server depends on libssl3 (>= 3.0.10). 508s openssh-client depends on libssl3 (>= 3.0.10). 508s mtd-utils depends on libssl3 (>= 3.0.0). 508s mokutil depends on libssl3 (>= 3.0.0). 508s linux-headers-6.8.0-11-generic depends on libssl3 (>= 3.0.0). 508s libsystemd-shared:arm64 depends on libssl3 (>= 3.0.0). 508s libssh-4:arm64 depends on libssl3 (>= 3.0.0). 508s libsasl2-modules:arm64 depends on libssl3 (>= 3.0.0). 508s libsasl2-2:arm64 depends on libssl3 (>= 3.0.0). 508s libpython3.12-minimal:arm64 depends on libssl3 (>= 3.0.0). 508s libnvme1 depends on libssl3 (>= 3.0.0). 508s libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). 508s libkmod2:arm64 depends on libssl3 (>= 3.0.0). 508s libfido2-1:arm64 depends on libssl3 (>= 3.0.0). 508s libcurl4:arm64 depends on libssl3 (>= 3.0.0). 508s libcryptsetup12:arm64 depends on libssl3 (>= 3.0.0). 508s kmod depends on libssl3 (>= 3.0.0). 508s dhcpcd-base depends on libssl3 (>= 3.0.0). 508s bind9-libs:arm64 depends on libssl3 (>= 3.0.0). 508s 508s (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 ... 74662 files and directories currently installed.) 508s Removing libssl3:arm64 (3.0.10-1ubuntu4) ... 509s Selecting previously unselected package libssl3t64:arm64. 509s (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 ... 74651 files and directories currently installed.) 509s Preparing to unpack .../0-libssl3t64_3.0.13-0ubuntu1_arm64.deb ... 509s Unpacking libssl3t64:arm64 (3.0.13-0ubuntu1) ... 509s Preparing to unpack .../1-ubuntu-minimal_1.535_arm64.deb ... 509s Unpacking ubuntu-minimal (1.535) over (1.534) ... 509s Selecting previously unselected package libnuma1:arm64. 509s Preparing to unpack .../2-libnuma1_2.0.18-1_arm64.deb ... 509s Unpacking libnuma1:arm64 (2.0.18-1) ... 509s Preparing to unpack .../3-ubuntu-standard_1.535_arm64.deb ... 509s Unpacking ubuntu-standard (1.535) over (1.534) ... 509s Preparing to unpack .../4-efibootmgr_18-1build1_arm64.deb ... 509s Unpacking efibootmgr (18-1build1) over (18-1) ... 509s Selecting previously unselected package libsensors-config. 509s Preparing to unpack .../5-libsensors-config_1%3a3.6.0-9_all.deb ... 509s Unpacking libsensors-config (1:3.6.0-9) ... 509s Selecting previously unselected package libsensors5:arm64. 509s Preparing to unpack .../6-libsensors5_1%3a3.6.0-9_arm64.deb ... 509s Unpacking libsensors5:arm64 (1:3.6.0-9) ... 509s Selecting previously unselected package numactl. 509s Preparing to unpack .../7-numactl_2.0.18-1_arm64.deb ... 509s Unpacking numactl (2.0.18-1) ... 509s Selecting previously unselected package sysstat. 509s Preparing to unpack .../8-sysstat_12.6.1-1ubuntu1_arm64.deb ... 509s Unpacking sysstat (12.6.1-1ubuntu1) ... 509s Setting up efibootmgr (18-1build1) ... 509s Setting up ubuntu-minimal (1.535) ... 509s Setting up libssl3t64:arm64 (3.0.13-0ubuntu1) ... 509s Setting up libsensors-config (1:3.6.0-9) ... 509s Setting up ubuntu-standard (1.535) ... 509s Setting up python3-cryptography (41.0.7-4build2) ... 510s Setting up libsensors5:arm64 (1:3.6.0-9) ... 510s Setting up libnuma1:arm64 (2.0.18-1) ... 510s Setting up openssl (3.0.13-0ubuntu1) ... 510s Setting up sysstat (12.6.1-1ubuntu1) ... 510s 510s Creating config file /etc/default/sysstat with new version 510s update-alternatives: using /usr/bin/sar.sysstat to provide /usr/bin/sar (sar) in auto mode 511s Created symlink /etc/systemd/system/sysstat.service.wants/sysstat-collect.timer → /usr/lib/systemd/system/sysstat-collect.timer. 511s Created symlink /etc/systemd/system/sysstat.service.wants/sysstat-summary.timer → /usr/lib/systemd/system/sysstat-summary.timer. 511s Created symlink /etc/systemd/system/multi-user.target.wants/sysstat.service → /usr/lib/systemd/system/sysstat.service. 515s Setting up numactl (2.0.18-1) ... 515s Processing triggers for man-db (2.12.0-3) ... 516s Processing triggers for libc-bin (2.39-0ubuntu2) ... 517s Reading package lists... 517s Building dependency tree... 517s Reading state information... 518s The following packages will be REMOVED: 518s ubuntu-advantage-tools* 519s 0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded. 519s After this operation, 71.7 kB disk space will be freed. 519s (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 ... 74757 files and directories currently installed.) 519s Removing ubuntu-advantage-tools (31.1) ... 519s (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 ... 74751 files and directories currently installed.) 519s Purging configuration files for ubuntu-advantage-tools (31.1) ... 520s sh: Attempting to set up Debian/Ubuntu apt sources automatically 520s sh: Distribution appears to be Ubuntu 521s Reading package lists... 522s Building dependency tree... 522s Reading state information... 523s eatmydata is already the newest version (131-1). 523s dbus is already the newest version (1.14.10-4ubuntu1). 523s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 523s Reading package lists... 524s Building dependency tree... 524s Reading state information... 525s rng-tools-debian is already the newest version (2.4). 525s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 525s Reading package lists... 526s Building dependency tree... 526s Reading state information... 528s haveged is already the newest version (1.9.14-1ubuntu1). 528s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 528s Reading package lists... 528s Building dependency tree... 528s Reading state information... 530s The following packages will be REMOVED: 530s cloud-init* python3-configobj* python3-debconf* 531s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 531s After this operation, 3248 kB disk space will be freed. 531s (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 ... 74750 files and directories currently installed.) 531s Removing cloud-init (24.1-0ubuntu1) ... 532s Removing python3-configobj (5.0.8-3) ... 532s Removing python3-debconf (1.5.86) ... 532s Processing triggers for man-db (2.12.0-3) ... 532s (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 ... 74361 files and directories currently installed.) 532s Purging configuration files for cloud-init (24.1-0ubuntu1) ... 535s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 535s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 536s Reading package lists... 536s Building dependency tree... 536s Reading state information... 537s linux-generic is already the newest version (6.8.0-11.11+1). 537s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 537s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 537s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 538s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 538s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 540s Reading package lists... 540s Reading package lists... 540s Building dependency tree... 540s Reading state information... 541s Calculating upgrade... 541s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 541s Reading package lists... 542s Building dependency tree... 542s Reading state information... 542s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 542s autopkgtest [16:47:32]: rebooting testbed after setup commands that affected boot 825s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 835s autopkgtest [16:52:25]: testbed dpkg architecture: arm64 839s Reading package lists... 839s Building dependency tree... 839s Reading state information... 840s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 840s Starting 2 pkgProblemResolver with broken count: 0 840s Done 841s Done 842s Starting pkgProblemResolver with broken count: 0 842s Starting 2 pkgProblemResolver with broken count: 0 842s Done 844s The following additional packages will be installed: 844s net-tools stunnel4 844s Suggested packages: 844s logcheck-database 844s The following NEW packages will be installed: 844s net-tools stunnel4 845s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 845s 1 not fully installed or removed. 845s Need to get 421 kB of archives. 845s After this operation, 1884 kB of additional disk space will be used. 845s Get:1 http://ftpmaster.internal/ubuntu noble/universe arm64 stunnel4 arm64 3:5.70-2 [215 kB] 845s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 net-tools arm64 2.10-0.1ubuntu3 [206 kB] 847s Fetched 421 kB in 1s (694 kB/s) 847s Selecting previously unselected package stunnel4. 847s (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 ... 74306 files and directories currently installed.) 847s Preparing to unpack .../stunnel4_3%3a5.70-2_arm64.deb ... 847s Unpacking stunnel4 (3:5.70-2) ... 847s Selecting previously unselected package net-tools. 847s Preparing to unpack .../net-tools_2.10-0.1ubuntu3_arm64.deb ... 847s Unpacking net-tools (2.10-0.1ubuntu3) ... 847s Setting up stunnel4 (3:5.70-2) ... 847s Creating group 'stunnel4' with GID 992. 847s Creating user 'stunnel4' (stunnel service system account) with UID 992 and GID 992. 849s Created symlink /etc/systemd/system/multi-user.target.wants/stunnel.target → /usr/lib/systemd/system/stunnel.target. 849s Setting up net-tools (2.10-0.1ubuntu3) ... 849s Setting up autopkgtest-satdep (0) ... 849s Processing triggers for man-db (2.12.0-3) ... 854s (Reading database ... 74399 files and directories currently installed.) 854s Removing autopkgtest-satdep (0) ... 856s autopkgtest [16:52:46]: test upstream: debian/tests/upstream 856s autopkgtest [16:52:46]: test upstream: [----------------------- 859s keys & certificates successfully generated 859s ./maketestcert.sh finished 860s 011. Test "cert" option 860s ...................................................................... succeeded 860s 012. Test no "cert" option 860s ...................................................................... succeeded 860s 021. Test "requireCert" option 860s ...................................................................... succeeded 860s 022. Failure test "requireCert" option 860s ...................................................................... succeeded 860s 031. Test "verifyPeer" option 860s ...................................................................... succeeded 860s 032. Failure test "verifyPeer" option 860s ...................................................................... succeeded 860s 041. Test "verifyChain" option 860s ...................................................................... succeeded 860s 042. Failure test "verifyChain" option 860s ...................................................................... succeeded 860s 051. CRL file with valid certificate 860s ...................................................................... succeeded 860s 052. CRL file with revoked certificate 860s ...................................................................... succeeded 860s 061. Test "PSKsecrets" option 860s ...................................................................... succeeded 860s 062. Failure test "PSKsecrets" option 860s ...................................................................... succeeded 860s 071. Test PKCS#12 certificate 860s ...................................................................... succeeded 860s 081. Test IPv6 support 860s ...................................................................... succeeded 860s 091. Binding server 860s ...................................................................... succeeded 860s 101. Test FIPS mode 860s child process pid 1303 exit status already read: will report returncode 255 860s ...................................................................... skipped 860s 111. Failure FIPS mode with unavailable cipher 860s ...................................................................... skipped 860s 112. Failure FIPS mode with unavailable ciphersuite 860s ...................................................................... skipped 860s 113. Failure FIPS mode with unavailable elliptic curve 860s ...................................................................... skipped 860s 121. Test "sni" option 860s ...................................................................... succeeded 860s 122. Failure test "sni" option 860s ...................................................................... succeeded 860s 131. Resume session with PSK authentication 860s ...................................................................... succeeded 860s 132. Cache session resumption with TLSv1.2 860s ...................................................................... succeeded 860s 133. Test enabled "sessionResume" option 860s ...................................................................... succeeded 860s 134. Test disabled "sessionResume" option 861s ...................................................................... succeeded 861s 141. Stateless session ticket resumption with TLSv1.2 861s ...................................................................... succeeded 861s 142. Stateless session ticket resumption with TLSv1.3 861s ...................................................................... succeeded 861s 151. Session resumption with secret keys 862s ...................................................................... succeeded 862s 161. Redirect connection (wrong peer certificate) 862s ...................................................................... succeeded 862s 162. Redirect connection (no peer certificate) 862s ...................................................................... succeeded 862s 163. Not redirected connection (valid peer certificate) 862s ...................................................................... succeeded 862s 164. Redirect connection (wrong chain) 862s ...................................................................... succeeded 862s 165. Redirect connection (no chain) 862s ...................................................................... succeeded 862s 166. Not redirected connection (valid chain) 862s ...................................................................... succeeded 862s 171. Redirect proxy connection (wrong peer certificate) 862s ...................................................................... succeeded 862s 172. Redirect proxy connection (no peer certificate) 862s ...................................................................... succeeded 862s 173. Not redirected proxy connection (valid peer certificate) 862s ...................................................................... succeeded 862s 181. Session resumption and redirect (wrong certificate) 862s ...................................................................... succeeded 862s 182. Session resumption and redirect (no certificate) 862s ...................................................................... succeeded 862s 183. Session resumption and no redirect (valid certificate) 862s ...................................................................... succeeded 862s 191. Reload configuration 862s ...................................................................... succeeded 862s 201. Failover round robin (rr) strategy 862s ...................................................................... succeeded 862s 202. Failover priority (prio) strategy 862s ...................................................................... succeeded 862s 211. Included configuration files 862s ...................................................................... succeeded 862s 221. Client inetd mode 862s ...................................................................... succeeded 862s 231. Test exec+connect service 862s ...................................................................... succeeded 862s 241. Resume session with delay option 863s ...................................................................... succeeded 863s 242. Resume session with disable delay option 863s ...................................................................... succeeded 863s 251. Wrong client configuration 863s ...................................................................... succeeded 863s 261. Wrong server configuration 863s ...................................................................... succeeded 863s 863s Summary: 863s succeeded: 46 863s failed: 0 863s skipped: 4 863s 863s Testing results: /tmp/autopkgtest.oCcs5T/build.Hu0/src/tests/logs/results.log 864s autopkgtest [16:52:54]: test upstream: -----------------------] 864s autopkgtest [16:52:54]: test upstream: - - - - - - - - - - results - - - - - - - - - - 864s upstream PASS 864s autopkgtest [16:52:54]: @@@@@@@@@@@@@@@@@@@@ summary 864s debian-python PASS 864s upstream PASS 884s Creating nova instance adt-noble-arm64-stunnel4-20240309-163830-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240308.img (UUID ddbc0ee7-bb97-4aa3-b5e1-9386758c2ba2)... 884s Creating nova instance adt-noble-arm64-stunnel4-20240309-163830-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240308.img (UUID ddbc0ee7-bb97-4aa3-b5e1-9386758c2ba2)...