0s autopkgtest [19:19:38]: starting date and time: 2025-03-15 19:19:38+0000 0s autopkgtest [19:19:38]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [19:19:38]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ha68nc9e/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-27.secgroup --name adt-plucky-s390x-stunnel4-20250315-191938-juju-7f2275-prod-proposed-migration-environment-15-e8ccf311-687d-4974-b02a-18274cafd2b8 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -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/ 73s autopkgtest [19:20:51]: testbed dpkg architecture: s390x 74s autopkgtest [19:20:52]: testbed apt version: 2.9.33 74s autopkgtest [19:20:52]: @@@@@@@@@@@@@@@@@@@@ test bed setup 74s autopkgtest [19:20:52]: testbed release detected to be: None 75s autopkgtest [19:20:53]: updating testbed package index (apt update) 75s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 75s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 75s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 76s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 76s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [45.1 kB] 76s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 76s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 76s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [77.3 kB] 76s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 76s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 76s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [314 kB] 76s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.3 kB] 76s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3532 B] 76s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 76s Fetched 965 kB in 1s (735 kB/s) 77s Reading package lists... 78s + lsb_release --codename --short 78s + RELEASE=plucky 78s + cat 78s + [ plucky != trusty ] 78s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Calculating upgrade... 78s Calculating upgrade... 78s The following packages were automatically installed and are no longer required: 78s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 78s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 78s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 78s linux-tools-6.11.0-8-generic 78s Use 'sudo apt autoremove' to remove them. 78s The following packages will be upgraded: 78s pinentry-curses python3-jinja2 strace 78s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 78s Need to get 652 kB of archives. 78s After this operation, 27.6 kB of additional disk space will be used. 78s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 79s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 79s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 79s Fetched 652 kB in 1s (804 kB/s) 79s (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 ... 81428 files and directories currently installed.) 79s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 79s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 79s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 79s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 79s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 79s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 79s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 79s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 79s Setting up strace (6.13+ds-1ubuntu1) ... 79s Processing triggers for man-db (2.13.0-1) ... 80s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 80s + /usr/lib/apt/apt-helper analyze-pattern ?true 80s + uname -r 80s + sed s/\./\\./g 80s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.* 80s + apt list ?obsolete 80s + tail -n+2 80s + cut+ grep -v ^linux-.*6\.14\.0-10-generic.* 80s -d/ -f1 80s + obsolete_pkgs=linux-headers-6.11.0-8-generic 80s linux-headers-6.11.0-8 80s linux-modules-6.11.0-8-generic 80s linux-tools-6.11.0-8-generic 80s linux-tools-6.11.0-8 80s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-headers-6.11.0-8 linux-modules-6.11.0-8-generic linux-tools-6.11.0-8-generic linux-tools-6.11.0-8 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Solving dependencies... 80s The following packages will be REMOVED: 80s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 80s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 80s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 80s linux-tools-6.11.0-8-generic* 80s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 80s After this operation, 167 MB disk space will be freed. 80s (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 ... 81428 files and directories currently installed.) 80s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 80s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 80s Removing libpython3.12t64:s390x (3.12.9-1) ... 80s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 81s Removing libnsl2:s390x (1.3.0-3build3) ... 81s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 81s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 81s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 81s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 81s Processing triggers for libc-bin (2.41-1ubuntu1) ... 81s (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 ... 56328 files and directories currently installed.) 81s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 81s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 82s + grep -q trusty /etc/lsb-release 82s + [ ! -d /usr/share/doc/unattended-upgrades ] 82s + [ ! -d /usr/share/doc/lxd ] 82s + [ ! -d /usr/share/doc/lxd-client ] 82s + [ ! -d /usr/share/doc/snapd ] 82s + type iptables 82s + cat 82s + chmod 755 /etc/rc.local 82s + . /etc/rc.local 82s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 82s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 82s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 82s + uname -m 82s + [ s390x = ppc64le ] 82s + [ -d /run/systemd/system ] 82s + systemd-detect-virt --quiet --vm 82s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 82s + cat 82s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 82s + echo COMPRESS=lz4 82s autopkgtest [19:21:00]: upgrading testbed (apt dist-upgrade and autopurge) 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 82s Starting 2 pkgProblemResolver with broken count: 0 82s Done 82s Entering ResolveByKeep 82s 83s Calculating upgrade... 83s The following packages will be upgraded: 83s libc-bin libc-dev-bin libc6 libc6-dev locales 83s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Need to get 9512 kB of archives. 83s After this operation, 8192 B of additional disk space will be used. 83s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 85s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 85s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 87s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 87s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 90s Preconfiguring packages ... 90s Fetched 9512 kB in 7s (1351 kB/s) 90s (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 ... 56326 files and directories currently installed.) 90s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 90s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 90s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 90s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 90s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 90s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 90s Setting up libc6:s390x (2.41-1ubuntu2) ... 90s (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 ... 56326 files and directories currently installed.) 90s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 90s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 90s Setting up libc-bin (2.41-1ubuntu2) ... 90s (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 ... 56326 files and directories currently installed.) 90s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 90s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 90s Setting up locales (2.41-1ubuntu2) ... 91s Generating locales (this might take a while)... 92s en_US.UTF-8... done 92s Generation complete. 92s Setting up libc-dev-bin (2.41-1ubuntu2) ... 92s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 92s Processing triggers for man-db (2.13.0-1) ... 92s Processing triggers for systemd (257.3-1ubuntu3) ... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Starting pkgProblemResolver with broken count: 0 93s Starting 2 pkgProblemResolver with broken count: 0 93s Done 94s Solving dependencies... 94s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 94s autopkgtest [19:21:12]: rebooting testbed after setup commands that affected boot 111s autopkgtest [19:21:29]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 113s autopkgtest [19:21:31]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 115s Get:1 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (dsc) [2845 B] 115s Get:2 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (tar) [904 kB] 115s Get:3 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (asc) [833 B] 115s Get:4 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (diff) [54.8 kB] 116s gpgv: Signature made Tue Jan 28 14:01:27 2025 UTC 116s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 116s gpgv: issuer "roam@debian.org" 116s gpgv: Can't check signature: No public key 116s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.74-2.dsc: no acceptable signature found 116s autopkgtest [19:21:34]: testing package stunnel4 version 3:5.74-2 116s autopkgtest [19:21:34]: build not needed 117s autopkgtest [19:21:35]: test debian-python: preparing testbed 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s Starting pkgProblemResolver with broken count: 0 117s Starting 2 pkgProblemResolver with broken count: 0 117s Done 117s The following NEW packages will be installed: 117s python3-all stunnel4 118s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 118s Need to get 227 kB of archives. 118s After this operation, 593 kB of additional disk space will be used. 118s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x stunnel4 s390x 3:5.74-2 [226 kB] 118s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3-all s390x 3.13.2-2 [886 B] 118s Fetched 227 kB in 1s (438 kB/s) 118s Selecting previously unselected package stunnel4. 118s (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 ... 56326 files and directories currently installed.) 118s Preparing to unpack .../stunnel4_3%3a5.74-2_s390x.deb ... 118s Unpacking stunnel4 (3:5.74-2) ... 118s Selecting previously unselected package python3-all. 118s Preparing to unpack .../python3-all_3.13.2-2_s390x.deb ... 118s Unpacking python3-all (3.13.2-2) ... 118s Setting up stunnel4 (3:5.74-2) ... 118s Creating group 'stunnel4' with GID 988. 118s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 119s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 119s Setting up python3-all (3.13.2-2) ... 119s Processing triggers for man-db (2.13.0-1) ... 120s autopkgtest [19:21:38]: 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 120s autopkgtest [19:21:38]: test debian-python: [----------------------- 120s 120s 120s ====== python3.13 120s 120s Using /tmp/struntime.sbo3tjnc as a temporary directory 120s Trying to obtain the version of /usr/bin/stunnel4 120s Started `/usr/bin/stunnel4 -version` as process 1471 120s Got stunnel version 5.74 120s [main] Starting to do things 120s [main] Awaiting the 'listener started' event 120s [find_listening_port] Looking for a port to listen on 120s [find_listening_port] Trying localhost port 6502 120s [listener] Telling the main thread about [127.0.0.1]:6502 120s [listener] Awaiting client connections... 120s [main] Apparently we are listening on [127.0.0.1]:6502 120s [main] Testing cleartext connections 120s [test_connect [127.0.0.1]:6502 0] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 1] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 2] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 3] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 4] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 5] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 6] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 7] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 8] Trying port 6502 encrypted False 120s [test_connect [127.0.0.1]:6502 9] Trying port 6502 encrypted False 121s [test_connect [127.0.0.1]:6502 1] Opening an unencrypted connection 121s [test_connect [127.0.0.1]:6502 3] Opening an unencrypted connection 121s [test_connect [127.0.0.1]:6502 4] Opening an unencrypted connection 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:40980] New connection 121s [[127.0.0.1]:40980] Telling the main thread 121s [[127.0.0.1]:40980] Let them tell us something... 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:40982] New connection 121s [[127.0.0.1]:40982] Telling the main thread 121s [[127.0.0.1]:40982] Let them tell us something... 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:40988] New connection 121s [[127.0.0.1]:40988] Telling the main thread 121s [[127.0.0.1]:40988] Let them tell us something... 121s [test_connect [127.0.0.1]:6502 1] Connected to the server: [127.0.0.1]:40980 121s [test_connect [127.0.0.1]:6502 3] Connected to the server: [127.0.0.1]:40982 121s [test_connect [127.0.0.1]:6502 4] Connected to the server: [127.0.0.1]:40988 121s [test_connect [127.0.0.1]:6502 0] Opening an unencrypted connection 121s [test_connect [127.0.0.1]:6502 8] Opening an unencrypted connection 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41002] New connection 121s [[127.0.0.1]:41002] Telling the main thread 121s [[127.0.0.1]:41002] Let them tell us something... 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41004] New connection 121s [[127.0.0.1]:41004] Telling the main thread 121s [[127.0.0.1]:41004] Let them tell us something... 121s [test_connect [127.0.0.1]:6502 0] Connected to the server: [127.0.0.1]:41002 121s [test_connect [127.0.0.1]:6502 8] Connected to the server: [127.0.0.1]:41004 121s [test_connect [127.0.0.1]:6502 5] Opening an unencrypted connection 121s [test_connect [127.0.0.1]:6502 9] Opening an unencrypted connection 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41006] New connection 121s [[127.0.0.1]:41006] Telling the main thread 121s [[127.0.0.1]:41006] Let them tell us something... 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41010] New connection 121s [[127.0.0.1]:41010] Telling the main thread 121s [[127.0.0.1]:41010] Let them tell us something... 121s [test_connect [127.0.0.1]:6502 5] Connected to the server: [127.0.0.1]:41006 121s [test_connect [127.0.0.1]:6502 9] Connected to the server: [127.0.0.1]:41010 121s [test_connect [127.0.0.1]:6502 1] Sending something... 121s [test_connect [127.0.0.1]:6502 1] Waiting for the main thread to figure it out 121s [[127.0.0.1]:40980] Telling the main thread about b'Hello 1\n' 121s [[127.0.0.1]:40980] Writing something back... 121s [[127.0.0.1]:40980] Telling the main thread about b'There!\n' 121s [[127.0.0.1]:40980] Closing down the writer socket 121s [test_connect [127.0.0.1]:6502 1] The listener acknowledged [127.0.0.1]:40980 121s [test_connect [127.0.0.1]:6502 1] Waiting for the server to receive it... 121s [test_connect [127.0.0.1]:6502 1] Waiting for the server to send something 121s [test_connect [127.0.0.1]:6502 1] Trying to receive the actual data 121s [test_connect [127.0.0.1]:6502 1] Waiting for the server to close the connection 121s [[127.0.0.1]:40980] Telling the main thread done 121s [test_connect [127.0.0.1]:6502 1] Waiting for an EOF on the reader socket 121s [test_connect [127.0.0.1]:6502 1] Closing our writer socket, too 121s [test_connect [127.0.0.1]:6502 1] Letting the main thread know we're done 121s [test_connect [127.0.0.1]:6502 1] Done 121s [process_connections] Fetching task plain-1 121s [process_connections] Waiting for task plain-1 121s [process_connections] Done with task plain-1 121s [test_connect [127.0.0.1]:6502 6] Opening an unencrypted connection 121s [test_connect [127.0.0.1]:6502 7] Opening an unencrypted connection 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41026] New connection 121s [[127.0.0.1]:41026] Telling the main thread 121s [[127.0.0.1]:41026] Let them tell us something... 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41040] New connection 121s [[127.0.0.1]:41040] Telling the main thread 121s [[127.0.0.1]:41040] Let them tell us something... 121s [test_connect [127.0.0.1]:6502 6] Connected to the server: [127.0.0.1]:41026 121s [test_connect [127.0.0.1]:6502 7] Connected to the server: [127.0.0.1]:41040 121s [test_connect [127.0.0.1]:6502 2] Opening an unencrypted connection 121s [listener] A client connected from somewhere 121s [[127.0.0.1]:41050] New connection 121s [[127.0.0.1]:41050] Telling the main thread 121s [[127.0.0.1]:41050] Let them tell us something... 121s [test_connect [127.0.0.1]:6502 2] Connected to the server: [127.0.0.1]:41050 121s [test_connect [127.0.0.1]:6502 5] Sending something... 121s [test_connect [127.0.0.1]:6502 5] Waiting for the main thread to figure it out 121s [[127.0.0.1]:41006] Telling the main thread about b'Hello 5\n' 121s [[127.0.0.1]:41006] Writing something back... 121s [[127.0.0.1]:41006] Telling the main thread about b'There!\n' 121s [[127.0.0.1]:41006] Closing down the writer socket 121s [test_connect [127.0.0.1]:6502 3] Sending something... 121s [test_connect [127.0.0.1]:6502 3] Waiting for the main thread to figure it out 121s [test_connect [127.0.0.1]:6502 5] The listener acknowledged [127.0.0.1]:41006 121s [test_connect [127.0.0.1]:6502 5] Waiting for the server to receive it... 121s [test_connect [127.0.0.1]:6502 5] Waiting for the server to send something 121s [test_connect [127.0.0.1]:6502 5] Trying to receive the actual data 121s [test_connect [127.0.0.1]:6502 5] Waiting for the server to close the connection 121s [[127.0.0.1]:41006] Telling the main thread done 121s [[127.0.0.1]:40982] Telling the main thread about b'Hello 3\n' 121s [[127.0.0.1]:40982] Writing something back... 121s [[127.0.0.1]:40982] Telling the main thread about b'There!\n' 121s [[127.0.0.1]:40982] Closing down the writer socket 121s [test_connect [127.0.0.1]:6502 5] Waiting for an EOF on the reader socket 121s [test_connect [127.0.0.1]:6502 5] Closing our writer socket, too 121s [test_connect [127.0.0.1]:6502 3] The listener acknowledged [127.0.0.1]:40982 121s [test_connect [127.0.0.1]:6502 3] Waiting for the server to receive it... 121s [test_connect [127.0.0.1]:6502 3] Waiting for the server to send something 121s [test_connect [127.0.0.1]:6502 3] Trying to receive the actual data 121s [test_connect [127.0.0.1]:6502 3] Waiting for the server to close the connection 121s [[127.0.0.1]:40982] Telling the main thread done 121s [test_connect [127.0.0.1]:6502 5] Letting the main thread know we're done 121s [test_connect [127.0.0.1]:6502 5] Done 121s [test_connect [127.0.0.1]:6502 3] Waiting for an EOF on the reader socket 121s [test_connect [127.0.0.1]:6502 3] Closing our writer socket, too 121s [process_connections] Fetching task plain-5 121s [process_connections] Waiting for task plain-5 121s [process_connections] Done with task plain-5 121s [test_connect [127.0.0.1]:6502 3] Letting the main thread know we're done 121s [test_connect [127.0.0.1]:6502 3] Done 121s [process_connections] Fetching task plain-3 121s [process_connections] Waiting for task plain-3 121s [process_connections] Done with task plain-3 122s [test_connect [127.0.0.1]:6502 9] Sending something... 122s [test_connect [127.0.0.1]:6502 9] Waiting for the main thread to figure it out 122s [[127.0.0.1]:41010] Telling the main thread about b'Hello 9\n' 122s [[127.0.0.1]:41010] Writing something back... 122s [[127.0.0.1]:41010] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41010] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 9] The listener acknowledged [127.0.0.1]:41010 122s [test_connect [127.0.0.1]:6502 9] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 9] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 9] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 9] Waiting for the server to close the connection 122s [[127.0.0.1]:41010] Telling the main thread done 122s [test_connect [127.0.0.1]:6502 9] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 9] Closing our writer socket, too 122s [test_connect [127.0.0.1]:6502 9] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 9] Done 122s [process_connections] Fetching task plain-9 122s [process_connections] Waiting for task plain-9 122s [process_connections] Done with task plain-9 122s [test_connect [127.0.0.1]:6502 0] Sending something... 122s [test_connect [127.0.0.1]:6502 0] Waiting for the main thread to figure it out 122s [test_connect [127.0.0.1]:6502 8] Sending something... 122s [test_connect [127.0.0.1]:6502 8] Waiting for the main thread to figure it out 122s [[127.0.0.1]:41002] Telling the main thread about b'Hello 0\n' 122s [[127.0.0.1]:41002] Writing something back... 122s [[127.0.0.1]:41002] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41002] Closing down the writer socket 122s [[127.0.0.1]:41004] Telling the main thread about b'Hello 8\n' 122s [[127.0.0.1]:41004] Writing something back... 122s [[127.0.0.1]:41004] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41004] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 4] Sending something... 122s [test_connect [127.0.0.1]:6502 4] Waiting for the main thread to figure it out 122s [test_connect [127.0.0.1]:6502 0] The listener acknowledged [127.0.0.1]:41002 122s [test_connect [127.0.0.1]:6502 0] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 0] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 0] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 0] Waiting for the server to close the connection 122s [test_connect [127.0.0.1]:6502 8] The listener acknowledged [127.0.0.1]:41004 122s [test_connect [127.0.0.1]:6502 8] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 8] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 8] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 8] Waiting for the server to close the connection 122s [[127.0.0.1]:41002] Telling the main thread done 122s [[127.0.0.1]:41004] Telling the main thread done 122s [[127.0.0.1]:40988] Telling the main thread about b'Hello 4\n' 122s [[127.0.0.1]:40988] Writing something back... 122s [[127.0.0.1]:40988] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:40988] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 0] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 0] Closing our writer socket, too 122s [test_connect [127.0.0.1]:6502 8] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 8] Closing our writer socket, too 122s [test_connect [127.0.0.1]:6502 4] The listener acknowledged [127.0.0.1]:40988 122s [test_connect [127.0.0.1]:6502 4] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 4] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 4] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 4] Waiting for the server to close the connection 122s [[127.0.0.1]:40988] Telling the main thread done 122s [test_connect [127.0.0.1]:6502 0] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 0] Done 122s [test_connect [127.0.0.1]:6502 8] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 8] Done 122s [test_connect [127.0.0.1]:6502 4] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 4] Closing our writer socket, too 122s [process_connections] Fetching task plain-0 122s [process_connections] Waiting for task plain-0 122s [process_connections] Done with task plain-0 122s [process_connections] Fetching task plain-8 122s [process_connections] Waiting for task plain-8 122s [process_connections] Done with task plain-8 122s [test_connect [127.0.0.1]:6502 4] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 4] Done 122s [process_connections] Fetching task plain-4 122s [process_connections] Waiting for task plain-4 122s [process_connections] Done with task plain-4 122s [test_connect [127.0.0.1]:6502 2] Sending something... 122s [test_connect [127.0.0.1]:6502 2] Waiting for the main thread to figure it out 122s [[127.0.0.1]:41050] Telling the main thread about b'Hello 2\n' 122s [[127.0.0.1]:41050] Writing something back... 122s [[127.0.0.1]:41050] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41050] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 2] The listener acknowledged [127.0.0.1]:41050 122s [test_connect [127.0.0.1]:6502 2] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 2] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 2] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 2] Waiting for the server to close the connection 122s [[127.0.0.1]:41050] Telling the main thread done 122s [test_connect [127.0.0.1]:6502 7] Sending something... 122s [test_connect [127.0.0.1]:6502 7] Waiting for the main thread to figure it out 122s [test_connect [127.0.0.1]:6502 2] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 2] Closing our writer socket, too 122s [[127.0.0.1]:41040] Telling the main thread about b'Hello 7\n' 122s [[127.0.0.1]:41040] Writing something back... 122s [[127.0.0.1]:41040] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41040] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 2] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 2] Done 122s [test_connect [127.0.0.1]:6502 7] The listener acknowledged [127.0.0.1]:41040 122s [test_connect [127.0.0.1]:6502 7] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 7] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 7] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 7] Waiting for the server to close the connection 122s [[127.0.0.1]:41040] Telling the main thread done 122s [process_connections] Fetching task plain-2 122s [process_connections] Waiting for task plain-2 122s [process_connections] Done with task plain-2 122s [test_connect [127.0.0.1]:6502 7] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 7] Closing our writer socket, too 122s [test_connect [127.0.0.1]:6502 7] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 7] Done 122s [process_connections] Fetching task plain-7 122s [process_connections] Waiting for task plain-7 122s [process_connections] Done with task plain-7 122s [test_connect [127.0.0.1]:6502 6] Sending something... 122s [test_connect [127.0.0.1]:6502 6] Waiting for the main thread to figure it out 122s [[127.0.0.1]:41026] Telling the main thread about b'Hello 6\n' 122s [[127.0.0.1]:41026] Writing something back... 122s [[127.0.0.1]:41026] Telling the main thread about b'There!\n' 122s [[127.0.0.1]:41026] Closing down the writer socket 122s [test_connect [127.0.0.1]:6502 6] The listener acknowledged [127.0.0.1]:41026 122s [test_connect [127.0.0.1]:6502 6] Waiting for the server to receive it... 122s [test_connect [127.0.0.1]:6502 6] Waiting for the server to send something 122s [test_connect [127.0.0.1]:6502 6] Trying to receive the actual data 122s [test_connect [127.0.0.1]:6502 6] Waiting for the server to close the connection 122s [[127.0.0.1]:41026] Telling the main thread done 122s [test_connect [127.0.0.1]:6502 6] Waiting for an EOF on the reader socket 122s [test_connect [127.0.0.1]:6502 6] Closing our writer socket, too 122s [test_connect [127.0.0.1]:6502 6] Letting the main thread know we're done 122s [test_connect [127.0.0.1]:6502 6] Done 122s [process_connections] Fetching task plain-6 122s [process_connections] Waiting for task plain-6 122s [process_connections] Done with task plain-6 122s [main] Picking a listen address:port for stunnel 122s [find_listening_port] Looking for a port to listen on 122s [find_listening_port] Trying 127.0.0.1 port 6503 122s [find_listening_port] got 6503, shutting down the listener 122s [main] Will put stunnel at 127.0.0.1:6503 122s [main] Preparing the stunnel config file 122s [main] Using config file /tmp/struntime.sbo3tjnc/stunnel.conf 122s [main] 122s [main] pid = /tmp/struntime.sbo3tjnc/stunnel.pid 122s [main] foreground = yes 122s [main] 122s [main] cert = /tmp/autopkgtest.lLrfBO/build.hyf/src/debian/tests/certs/certificate.pem 122s [main] key = /tmp/struntime.sbo3tjnc/key.pem 122s [main] 122s [main] [test] 122s [main] accept = 127.0.0.1:6503 122s [main] connect = 127.0.0.1:6502 122s [main] End of the config file 122s [main] Launching the stunnel server 122s [main] - got pid 1475 122s [main] Launching the stunnel output pipe thread 122s [main] Waiting for stunnel to start up 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: stunnel 5.74 on s390x-ibm-linux-gnu platform' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: Compiled with OpenSSL 3.4.0 22 Oct 2024' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: Running with OpenSSL 3.4.1 11 Feb 2025' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: Reading configuration from file /tmp/struntime.sbo3tjnc/stunnel.conf' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: UTF-8 byte order mark not detected' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: FIPS mode disabled' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[ui]: Configuration successful' 122s [stunnel_output] The main thread ought to know 122s [main] Testing the encrypted connections 122s [test_connect [127.0.0.1]:6503 0] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 1] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 2] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 3] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 4] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 5] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 6] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 7] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 8] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 9] Trying port 6503 encrypted True 122s [test_connect [127.0.0.1]:6503 5] Creating an SSL context 122s [test_connect [127.0.0.1]:6503 5] - cert required 122s [test_connect [127.0.0.1]:6503 5] - load_verify_locations() 122s [test_connect [127.0.0.1]:6503 5] Opening an SSL connection 122s [test_connect [127.0.0.1]:6503 7] Creating an SSL context 122s [test_connect [127.0.0.1]:6503 7] - cert required 122s [test_connect [127.0.0.1]:6503 7] - load_verify_locations() 122s [test_connect [127.0.0.1]:6503 7] Opening an SSL connection 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[0]: Service [test] accepted connection from 127.0.0.1:41510' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[1]: Service [test] accepted connection from 127.0.0.1:41516' 122s [test_connect [127.0.0.1]:6503 5] Connected to the server: [127.0.0.1]:41510 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[0]: s_connect: connected 127.0.0.1:6502' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[0]: Service [test] connected remote server from 127.0.0.1:41056' 122s [listener] A client connected from somewhere 122s [[127.0.0.1]:41056] New connection 122s [[127.0.0.1]:41056] Telling the main thread 122s [[127.0.0.1]:41056] Let them tell us something... 122s [test_connect [127.0.0.1]:6503 7] Connected to the server: [127.0.0.1]:41516 122s [listener] A client connected from somewhere 122s [[127.0.0.1]:41062] New connection 122s [[127.0.0.1]:41062] Telling the main thread 122s [[127.0.0.1]:41062] Let them tell us something... 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[1]: s_connect: connected 127.0.0.1:6502' 122s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[1]: Service [test] connected remote server from 127.0.0.1:41062' 123s [test_connect [127.0.0.1]:6503 1] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 1] - cert required 123s [test_connect [127.0.0.1]:6503 1] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 1] Opening an SSL connection 123s [test_connect [127.0.0.1]:6503 4] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 4] - cert required 123s [test_connect [127.0.0.1]:6503 4] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 4] Opening an SSL connection 123s [test_connect [127.0.0.1]:6503 8] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 8] - cert required 123s [test_connect [127.0.0.1]:6503 8] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 8] Opening an SSL connection 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[2]: Service [test] accepted connection from 127.0.0.1:41530' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[3]: Service [test] accepted connection from 127.0.0.1:41546' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[4]: Service [test] accepted connection from 127.0.0.1:41548' 123s [test_connect [127.0.0.1]:6503 1] Connected to the server: [127.0.0.1]:41530 123s [test_connect [127.0.0.1]:6503 4] Connected to the server: [127.0.0.1]:41546 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[3]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[3]: Service [test] connected remote server from 127.0.0.1:41074' 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41074] New connection 123s [[127.0.0.1]:41074] Telling the main thread 123s [[127.0.0.1]:41074] Let them tell us something... 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[2]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[2]: Service [test] connected remote server from 127.0.0.1:41090' 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41090] New connection 123s [[127.0.0.1]:41090] Telling the main thread 123s [[127.0.0.1]:41090] Let them tell us something... 123s [test_connect [127.0.0.1]:6503 8] Connected to the server: [127.0.0.1]:41548 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41092] New connection 123s [[127.0.0.1]:41092] Telling the main thread 123s [[127.0.0.1]:41092] Let them tell us something... 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[4]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[4]: Service [test] connected remote server from 127.0.0.1:41092' 123s [test_connect [127.0.0.1]:6503 3] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 3] - cert required 123s [test_connect [127.0.0.1]:6503 3] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 3] Opening an SSL connection 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[5]: Service [test] accepted connection from 127.0.0.1:41564' 123s [test_connect [127.0.0.1]:6503 3] Connected to the server: [127.0.0.1]:41564 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41100] New connection 123s [[127.0.0.1]:41100] Telling the main thread 123s [[127.0.0.1]:41100] Let them tell us something... 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[5]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[5]: Service [test] connected remote server from 127.0.0.1:41100' 123s [test_connect [127.0.0.1]:6503 2] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 2] - cert required 123s [test_connect [127.0.0.1]:6503 2] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 2] Opening an SSL connection 123s [test_connect [127.0.0.1]:6503 9] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 9] - cert required 123s [test_connect [127.0.0.1]:6503 9] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 9] Opening an SSL connection 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[6]: Service [test] accepted connection from 127.0.0.1:41574' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[7]: Service [test] accepted connection from 127.0.0.1:41578' 123s [test_connect [127.0.0.1]:6503 2] Connected to the server: [127.0.0.1]:41574 123s [test_connect [127.0.0.1]:6503 9] Connected to the server: [127.0.0.1]:41578 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[6]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[6]: Service [test] connected remote server from 127.0.0.1:41102' 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41102] New connection 123s [[127.0.0.1]:41102] Telling the main thread 123s [[127.0.0.1]:41102] Let them tell us something... 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[7]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[7]: Service [test] connected remote server from 127.0.0.1:41112' 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41112] New connection 123s [[127.0.0.1]:41112] Telling the main thread 123s [[127.0.0.1]:41112] Let them tell us something... 123s [test_connect [127.0.0.1]:6503 3] Sending something... 123s [test_connect [127.0.0.1]:6503 3] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41100] Telling the main thread about b'Hello 3\n' 123s [[127.0.0.1]:41100] Writing something back... 123s [[127.0.0.1]:41100] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41100] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 3] The listener acknowledged [127.0.0.1]:41100 123s [test_connect [127.0.0.1]:6503 3] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 3] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 3] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 3] Waiting for the server to close the connection 123s [[127.0.0.1]:41100] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 3] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 3] Closing our writer socket, too 123s [test_connect [127.0.0.1]:6503 3] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 3] Done 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [process_connections] Fetching task encr-3 123s [process_connections] Waiting for task encr-3 123s [process_connections] Done with task encr-3 123s [test_connect [127.0.0.1]:6503 7] Sending something... 123s [test_connect [127.0.0.1]:6503 7] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41062] Telling the main thread about b'Hello 7\n' 123s [[127.0.0.1]:41062] Writing something back... 123s [[127.0.0.1]:41062] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41062] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 7] The listener acknowledged [127.0.0.1]:41062 123s [test_connect [127.0.0.1]:6503 7] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 7] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 7] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 7] Waiting for the server to close the connection 123s [[127.0.0.1]:41062] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 7] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 7] Closing our writer socket, too 123s [test_connect [127.0.0.1]:6503 7] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 7] Done 123s [stunnel_output] Read '2025.03.15 19:23:06 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [process_connections] Fetching task encr-7 123s [process_connections] Waiting for task encr-7 123s [process_connections] Done with task encr-7 123s [test_connect [127.0.0.1]:6503 0] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 0] - cert required 123s [test_connect [127.0.0.1]:6503 0] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 0] Opening an SSL connection 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[8]: Service [test] accepted connection from 127.0.0.1:41584' 123s [test_connect [127.0.0.1]:6503 0] Connected to the server: [127.0.0.1]:41584 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[8]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[8]: Service [test] connected remote server from 127.0.0.1:41126' 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41126] New connection 123s [[127.0.0.1]:41126] Telling the main thread 123s [[127.0.0.1]:41126] Let them tell us something... 123s [test_connect [127.0.0.1]:6503 5] Sending something... 123s [test_connect [127.0.0.1]:6503 5] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41056] Telling the main thread about b'Hello 5\n' 123s [[127.0.0.1]:41056] Writing something back... 123s [[127.0.0.1]:41056] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41056] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 5] The listener acknowledged [127.0.0.1]:41056 123s [test_connect [127.0.0.1]:6503 5] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 5] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 5] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 5] Waiting for the server to close the connection 123s [[127.0.0.1]:41056] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 5] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 5] Closing our writer socket, too 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [test_connect [127.0.0.1]:6503 5] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 5] Done 123s [process_connections] Fetching task encr-5 123s [process_connections] Waiting for task encr-5 123s [process_connections] Done with task encr-5 123s [test_connect [127.0.0.1]:6503 6] Creating an SSL context 123s [test_connect [127.0.0.1]:6503 6] - cert required 123s [test_connect [127.0.0.1]:6503 6] - load_verify_locations() 123s [test_connect [127.0.0.1]:6503 6] Opening an SSL connection 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[9]: Service [test] accepted connection from 127.0.0.1:41592' 123s [test_connect [127.0.0.1]:6503 6] Connected to the server: [127.0.0.1]:41592 123s [listener] A client connected from somewhere 123s [[127.0.0.1]:41132] New connection 123s [[127.0.0.1]:41132] Telling the main thread 123s [[127.0.0.1]:41132] Let them tell us something... 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[9]: s_connect: connected 127.0.0.1:6502' 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[9]: Service [test] connected remote server from 127.0.0.1:41132' 123s [test_connect [127.0.0.1]:6503 2] Sending something... 123s [test_connect [127.0.0.1]:6503 2] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41102] Telling the main thread about b'Hello 2\n' 123s [[127.0.0.1]:41102] Writing something back... 123s [[127.0.0.1]:41102] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41102] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 2] The listener acknowledged [127.0.0.1]:41102 123s [test_connect [127.0.0.1]:6503 2] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 2] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 2] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 2] Waiting for the server to close the connection 123s [[127.0.0.1]:41102] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 2] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 2] Closing our writer socket, too 123s [test_connect [127.0.0.1]:6503 2] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 2] Done 123s [process_connections] Fetching task encr-2 123s [process_connections] Waiting for task encr-2 123s [process_connections] Done with task encr-2 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [test_connect [127.0.0.1]:6503 8] Sending something... 123s [test_connect [127.0.0.1]:6503 8] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41092] Telling the main thread about b'Hello 8\n' 123s [[127.0.0.1]:41092] Writing something back... 123s [[127.0.0.1]:41092] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41092] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 8] The listener acknowledged [127.0.0.1]:41092 123s [test_connect [127.0.0.1]:6503 8] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 8] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 8] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 8] Waiting for the server to close the connection 123s [[127.0.0.1]:41092] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 8] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 8] Closing our writer socket, too 123s [test_connect [127.0.0.1]:6503 8] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 8] Done 123s [process_connections] Fetching task encr-8 123s [process_connections] Waiting for task encr-8 123s [process_connections] Done with task encr-8 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [test_connect [127.0.0.1]:6503 1] Sending something... 123s [test_connect [127.0.0.1]:6503 1] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41090] Telling the main thread about b'Hello 1\n' 123s [[127.0.0.1]:41090] Writing something back... 123s [[127.0.0.1]:41090] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41090] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 1] The listener acknowledged [127.0.0.1]:41090 123s [test_connect [127.0.0.1]:6503 1] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 1] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 1] Trying to receive the actual data 123s [[127.0.0.1]:41090] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 1] Waiting for the server to close the connection 123s [test_connect [127.0.0.1]:6503 1] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 1] Closing our writer socket, too 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [test_connect [127.0.0.1]:6503 1] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 1] Done 123s [process_connections] Fetching task encr-1 123s [process_connections] Waiting for task encr-1 123s [process_connections] Done with task encr-1 123s [test_connect [127.0.0.1]:6503 4] Sending something... 123s [test_connect [127.0.0.1]:6503 4] Waiting for the main thread to figure it out 123s [[127.0.0.1]:41074] Telling the main thread about b'Hello 4\n' 123s [[127.0.0.1]:41074] Writing something back... 123s [[127.0.0.1]:41074] Telling the main thread about b'There!\n' 123s [[127.0.0.1]:41074] Closing down the writer socket 123s [test_connect [127.0.0.1]:6503 4] The listener acknowledged [127.0.0.1]:41074 123s [test_connect [127.0.0.1]:6503 4] Waiting for the server to receive it... 123s [test_connect [127.0.0.1]:6503 4] Waiting for the server to send something 123s [test_connect [127.0.0.1]:6503 4] Trying to receive the actual data 123s [test_connect [127.0.0.1]:6503 4] Waiting for the server to close the connection 123s [[127.0.0.1]:41074] Telling the main thread done 123s [test_connect [127.0.0.1]:6503 4] Waiting for an EOF on the reader socket 123s [test_connect [127.0.0.1]:6503 4] Closing our writer socket, too 123s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 123s [test_connect [127.0.0.1]:6503 4] Letting the main thread know we're done 123s [test_connect [127.0.0.1]:6503 4] Done 123s [process_connections] Fetching task encr-4 123s [process_connections] Waiting for task encr-4 123s [process_connections] Done with task encr-4 124s [test_connect [127.0.0.1]:6503 0] Sending something... 124s [test_connect [127.0.0.1]:6503 0] Waiting for the main thread to figure it out 124s [[127.0.0.1]:41126] Telling the main thread about b'Hello 0\n' 124s [[127.0.0.1]:41126] Writing something back... 124s [[127.0.0.1]:41126] Telling the main thread about b'There!\n' 124s [[127.0.0.1]:41126] Closing down the writer socket 124s [test_connect [127.0.0.1]:6503 0] The listener acknowledged [127.0.0.1]:41126 124s [test_connect [127.0.0.1]:6503 0] Waiting for the server to receive it... 124s [test_connect [127.0.0.1]:6503 0] Waiting for the server to send something 124s [test_connect [127.0.0.1]:6503 0] Trying to receive the actual data 124s [test_connect [127.0.0.1]:6503 0] Waiting for the server to close the connection 124s [[127.0.0.1]:41126] Telling the main thread done 124s [test_connect [127.0.0.1]:6503 0] Waiting for an EOF on the reader socket 124s [test_connect [127.0.0.1]:6503 0] Closing our writer socket, too 124s [test_connect [127.0.0.1]:6503 0] Letting the main thread know we're done 124s [test_connect [127.0.0.1]:6503 0] Done 124s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 124s [process_connections] Fetching task encr-0 124s [process_connections] Waiting for task encr-0 124s [process_connections] Done with task encr-0 124s [test_connect [127.0.0.1]:6503 6] Sending something... 124s [test_connect [127.0.0.1]:6503 6] Waiting for the main thread to figure it out 124s [[127.0.0.1]:41132] Telling the main thread about b'Hello 6\n' 124s [[127.0.0.1]:41132] Writing something back... 124s [[127.0.0.1]:41132] Telling the main thread about b'There!\n' 124s [[127.0.0.1]:41132] Closing down the writer socket 124s [test_connect [127.0.0.1]:6503 6] The listener acknowledged [127.0.0.1]:41132 124s [test_connect [127.0.0.1]:6503 6] Waiting for the server to receive it... 124s [test_connect [127.0.0.1]:6503 6] Waiting for the server to send something 124s [test_connect [127.0.0.1]:6503 6] Trying to receive the actual data 124s [test_connect [127.0.0.1]:6503 6] Waiting for the server to close the connection 124s [[127.0.0.1]:41132] Telling the main thread done 124s [test_connect [127.0.0.1]:6503 6] Waiting for an EOF on the reader socket 124s [test_connect [127.0.0.1]:6503 6] Closing our writer socket, too 124s [test_connect [127.0.0.1]:6503 6] Letting the main thread know we're done 124s [test_connect [127.0.0.1]:6503 6] Done 124s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 124s [process_connections] Fetching task encr-6 124s [process_connections] Waiting for task encr-6 124s [process_connections] Done with task encr-6 124s [test_connect [127.0.0.1]:6503 9] Sending something... 124s [test_connect [127.0.0.1]:6503 9] Waiting for the main thread to figure it out 124s [[127.0.0.1]:41112] Telling the main thread about b'Hello 9\n' 124s [[127.0.0.1]:41112] Writing something back... 124s [[127.0.0.1]:41112] Telling the main thread about b'There!\n' 124s [[127.0.0.1]:41112] Closing down the writer socket 124s [test_connect [127.0.0.1]:6503 9] The listener acknowledged [127.0.0.1]:41112 124s [test_connect [127.0.0.1]:6503 9] Waiting for the server to receive it... 124s [test_connect [127.0.0.1]:6503 9] Waiting for the server to send something 124s [test_connect [127.0.0.1]:6503 9] Trying to receive the actual data 124s [test_connect [127.0.0.1]:6503 9] Waiting for the server to close the connection 124s [[127.0.0.1]:41112] Telling the main thread done 124s [test_connect [127.0.0.1]:6503 9] Waiting for an EOF on the reader socket 124s [test_connect [127.0.0.1]:6503 9] Closing our writer socket, too 124s [stunnel_output] Read '2025.03.15 19:23:07 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 124s [test_connect [127.0.0.1]:6503 9] Letting the main thread know we're done 124s [test_connect [127.0.0.1]:6503 9] Done 124s [process_connections] Fetching task encr-9 124s [process_connections] Waiting for task encr-9 124s [process_connections] Done with task encr-9 124s [main] Everything seems to be all right! 124s About to cancel 2 remaining task(s) 124s - listen 124s - output 124s Waiting for the tasks to hopefully finish 124s About to kill and wait for 1 child process(es) 124s - pid 1475 124s Waiting for the processes to exit... 124s Got processes' exit status: [-9] 124s autopkgtest [19:21:42]: test debian-python: -----------------------] 125s debian-python PASS 125s autopkgtest [19:21:43]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 125s autopkgtest [19:21:43]: test upstream: preparing testbed 195s autopkgtest [19:22:53]: testbed dpkg architecture: s390x 196s autopkgtest [19:22:54]: testbed apt version: 2.9.33 196s autopkgtest [19:22:54]: @@@@@@@@@@@@@@@@@@@@ test bed setup 196s autopkgtest [19:22:54]: testbed release detected to be: plucky 197s autopkgtest [19:22:55]: updating testbed package index (apt update) 197s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 197s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 198s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 198s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 198s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 198s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [45.1 kB] 198s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 198s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [77.3 kB] 198s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 198s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 198s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [314 kB] 198s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.3 kB] 198s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3532 B] 198s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 198s Fetched 965 kB in 1s (1221 kB/s) 199s Reading package lists... 199s + lsb_release --codename --short 199s + RELEASE=plucky 199s + cat 199s + [ plucky != trusty ] 199s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 199s Reading package lists... 199s Building dependency tree... 199s Reading state information... 199s Calculating upgrade... 200s Calculating upgrade... 200s The following packages were automatically installed and are no longer required: 200s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 200s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 200s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 200s linux-tools-6.11.0-8-generic 200s Use 'sudo apt autoremove' to remove them. 200s The following packages will be upgraded: 200s pinentry-curses python3-jinja2 strace 200s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 200s Need to get 652 kB of archives. 200s After this operation, 27.6 kB of additional disk space will be used. 200s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 200s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 200s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 201s Fetched 652 kB in 1s (961 kB/s) 201s (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 ... 81428 files and directories currently installed.) 201s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 201s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 201s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 201s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 201s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 201s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 201s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 201s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 201s Setting up strace (6.13+ds-1ubuntu1) ... 201s Processing triggers for man-db (2.13.0-1) ... 202s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 202s + /usr/lib/apt/apt-helper analyze-pattern ?true 202s + uname -r 202s + sed s/\./\\./g 202s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.* 202s + apt list ?obsolete 202s + tail -n+2 202s + cut+ grep -v ^linux-.*6\.14\.0-10-generic.* 202s -d/ -f1 202s + obsolete_pkgs=linux-headers-6.11.0-8-generic 202s linux-headers-6.11.0-8 202s linux-modules-6.11.0-8-generic 202s linux-tools-6.11.0-8-generic 202s linux-tools-6.11.0-8 202s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-headers-6.11.0-8 linux-modules-6.11.0-8-generic linux-tools-6.11.0-8-generic linux-tools-6.11.0-8 202s Reading package lists... 202s Building dependency tree... 202s Reading state information... 202s Solving dependencies... 202s The following packages will be REMOVED: 202s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 202s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 202s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 202s linux-tools-6.11.0-8-generic* 202s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 202s After this operation, 167 MB disk space will be freed. 202s (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 ... 81428 files and directories currently installed.) 202s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 202s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 202s Removing libpython3.12t64:s390x (3.12.9-1) ... 202s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 202s Removing libnsl2:s390x (1.3.0-3build3) ... 202s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 202s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 202s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 203s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 203s Processing triggers for libc-bin (2.41-1ubuntu1) ... 203s (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 ... 56328 files and directories currently installed.) 203s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 203s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 203s + grep -q trusty /etc/lsb-release 203s + [ ! -d /usr/share/doc/unattended-upgrades ] 203s + [ ! -d /usr/share/doc/lxd ] 203s + [ ! -d /usr/share/doc/lxd-client ] 203s + [ ! -d /usr/share/doc/snapd ] 203s + type iptables 203s + cat 203s + chmod 755 /etc/rc.local 203s + . /etc/rc.local 203s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 203s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 203s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 203s + uname -m 203s + [ s390x = ppc64le ] 203s + [ -d /run/systemd/system ] 203s + systemd-detect-virt --quiet --vm 203s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 203s + cat 203s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 203s + echo COMPRESS=lz4 203s autopkgtest [19:23:01]: upgrading testbed (apt dist-upgrade and autopurge) 204s Reading package lists... 204s Building dependency tree... 204s Reading state information... 204s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 204s Starting 2 pkgProblemResolver with broken count: 0 204s Done 204s Entering ResolveByKeep 204s 204s Calculating upgrade... 204s The following packages will be upgraded: 204s libc-bin libc-dev-bin libc6 libc6-dev locales 205s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 205s Need to get 9512 kB of archives. 205s After this operation, 8192 B of additional disk space will be used. 205s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 206s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 206s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 206s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 207s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 207s Preconfiguring packages ... 207s Fetched 9512 kB in 3s (3505 kB/s) 207s (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 ... 56326 files and directories currently installed.) 207s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 207s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 208s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 208s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 208s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 208s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 208s Setting up libc6:s390x (2.41-1ubuntu2) ... 208s (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 ... 56326 files and directories currently installed.) 208s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 208s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 208s Setting up libc-bin (2.41-1ubuntu2) ... 208s (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 ... 56326 files and directories currently installed.) 208s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 208s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 208s Setting up locales (2.41-1ubuntu2) ... 208s Generating locales (this might take a while)... 209s en_US.UTF-8... done 209s Generation complete. 209s Setting up libc-dev-bin (2.41-1ubuntu2) ... 209s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 209s Processing triggers for man-db (2.13.0-1) ... 210s Processing triggers for systemd (257.3-1ubuntu3) ... 211s Reading package lists... 211s Building dependency tree... 211s Reading state information... 211s Starting pkgProblemResolver with broken count: 0 211s Starting 2 pkgProblemResolver with broken count: 0 211s Done 211s Solving dependencies... 211s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 212s autopkgtest [19:23:10]: rebooting testbed after setup commands that affected boot 234s Reading package lists... 234s Building dependency tree... 234s Reading state information... 234s Starting pkgProblemResolver with broken count: 0 234s Starting 2 pkgProblemResolver with broken count: 0 234s Done 234s The following NEW packages will be installed: 234s net-tools stunnel4 234s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 234s Need to get 433 kB of archives. 234s After this operation, 1365 kB of additional disk space will be used. 234s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x stunnel4 s390x 3:5.74-2 [226 kB] 235s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x net-tools s390x 2.10-1.1ubuntu1 [207 kB] 235s Fetched 433 kB in 1s (712 kB/s) 235s Selecting previously unselected package stunnel4. 235s (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 ... 56326 files and directories currently installed.) 235s Preparing to unpack .../stunnel4_3%3a5.74-2_s390x.deb ... 235s Unpacking stunnel4 (3:5.74-2) ... 235s Selecting previously unselected package net-tools. 235s Preparing to unpack .../net-tools_2.10-1.1ubuntu1_s390x.deb ... 235s Unpacking net-tools (2.10-1.1ubuntu1) ... 235s Setting up stunnel4 (3:5.74-2) ... 235s Creating group 'stunnel4' with GID 988. 235s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 236s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 236s Setting up net-tools (2.10-1.1ubuntu1) ... 236s Processing triggers for man-db (2.13.0-1) ... 242s autopkgtest [19:23:40]: test upstream: debian/tests/upstream 242s autopkgtest [19:23:40]: test upstream: [----------------------- 243s keys & certificates successfully generated 243s ./maketestcert.sh finished 244s Stunnel was compiled and run with different OpenSSL versions 244s autopkgtest [19:23:42]: test upstream: -----------------------] 244s autopkgtest [19:23:42]: test upstream: - - - - - - - - - - results - - - - - - - - - - 244s upstream PASS 245s autopkgtest [19:23:43]: @@@@@@@@@@@@@@@@@@@@ summary 245s debian-python PASS 245s upstream PASS 262s nova [W] Using flock in prodstack6-s390x 262s Creating nova instance adt-plucky-s390x-stunnel4-20250315-191938-juju-7f2275-prod-proposed-migration-environment-15-e8ccf311-687d-4974-b02a-18274cafd2b8 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 262s nova [W] Timed out waiting for e1fb4cc4-a527-4bc9-bd8c-831f5fbadd12 to get deleted. 262s nova [W] Using flock in prodstack6-s390x 262s Creating nova instance adt-plucky-s390x-stunnel4-20250315-191938-juju-7f2275-prod-proposed-migration-environment-15-e8ccf311-687d-4974-b02a-18274cafd2b8 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 262s nova [W] Timed out waiting for a5c18c82-b500-43d8-80a2-fcd2ebc99693 to get deleted.