0s autopkgtest [11:30:56]: starting date and time: 2024-11-09 11:30:56+0000 0s autopkgtest [11:30:56]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [11:30:56]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.y3apj6h1/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python-cryptography,src:python-cryptography-vectors,src:sphinx-rtd-theme --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python-cryptography/43.0.0-1 python-cryptography-vectors/43.0.0-1 sphinx-rtd-theme/3.0.1+dfsg-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-27.secgroup --name adt-plucky-ppc64el-stunnel4-20241109-113056-juju-7f2275-prod-proposed-migration-environment-2-80068727-98eb-4237-9c78-a59540e0ea37 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 54s autopkgtest [11:31:50]: testbed dpkg architecture: ppc64el 54s autopkgtest [11:31:50]: testbed apt version: 2.9.8 54s autopkgtest [11:31:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 55s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 55s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1055 kB] 56s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [107 kB] 56s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.4 kB] 56s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 56s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [135 kB] 56s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [757 kB] 56s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [21.3 kB] 56s Fetched 2174 kB in 1s (2452 kB/s) 56s Reading package lists... 58s Reading package lists... 58s Building dependency tree... 58s Reading state information... 59s Calculating upgrade... 59s The following NEW packages will be installed: 59s python3-bcrypt systemd-cryptsetup 59s The following packages will be upgraded: 59s base-passwd bash-completion libattr1 libblockdev-crypto3 libblockdev-fs3 59s libblockdev-loop3 libblockdev-mdraid3 libblockdev-nvme3 libblockdev-part3 59s libblockdev-swap3 libblockdev-utils3 libblockdev3 libbpf1 libbsd0 59s libbytesize-common libbytesize1 libdrm-common libdrm2 libjson-glib-1.0-0 59s libjson-glib-1.0-common libmaxminddb0 libmnl0 libnghttp2-14 libnspr4 59s libnss-systemd libpam-systemd libpython3.12-minimal libpython3.12-stdlib 59s libpython3.12t64 libsystemd-shared libsystemd0 libudev1 libudisks2-0 59s liburcu8t64 linux-firmware openssh-client openssh-server openssh-sftp-server 59s pci.ids python3-cryptography python3-gi python3-idna python3-jsonpatch 59s python3-lazr.restfulclient python3-urllib3 python3-wadllib python3.12 59s python3.12-gdbm python3.12-minimal systemd systemd-resolved systemd-sysv 59s systemd-timesyncd tzdata udev udisks2 usbutils 59s 57 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 59s Need to get 561 MB of archives. 59s After this operation, 28.2 MB of additional disk space will be used. 59s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el base-passwd ppc64el 3.6.5 [55.1 kB] 59s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12t64 ppc64el 3.12.7-2 [2565 kB] 59s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12 ppc64el 3.12.7-2 [661 kB] 59s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12-stdlib ppc64el 3.12.7-2 [2121 kB] 59s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12-minimal ppc64el 3.12.7-2 [2467 kB] 60s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12-minimal ppc64el 3.12.7-2 [839 kB] 60s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el tzdata all 2024b-1ubuntu2 [274 kB] 60s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnss-systemd ppc64el 256.5-2ubuntu4 [215 kB] 60s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-timesyncd ppc64el 256.5-2ubuntu4 [42.5 kB] 60s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-resolved ppc64el 256.5-2ubuntu4 [368 kB] 60s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsystemd-shared ppc64el 256.5-2ubuntu4 [2503 kB] 60s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsystemd0 ppc64el 256.5-2ubuntu4 [565 kB] 60s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-sysv ppc64el 256.5-2ubuntu4 [11.9 kB] 60s Get:14 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpam-systemd ppc64el 256.5-2ubuntu4 [326 kB] 60s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd ppc64el 256.5-2ubuntu4 [3746 kB] 60s Get:16 http://ftpmaster.internal/ubuntu plucky/main ppc64el udev ppc64el 256.5-2ubuntu4 [2141 kB] 60s Get:17 http://ftpmaster.internal/ubuntu plucky/main ppc64el libudev1 ppc64el 256.5-2ubuntu4 [224 kB] 60s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-sftp-server ppc64el 1:9.7p1-7ubuntu5 [43.3 kB] 60s Get:19 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-server ppc64el 1:9.7p1-7ubuntu5 [629 kB] 60s Get:20 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-client ppc64el 1:9.7p1-7ubuntu5 [1105 kB] 60s Get:21 http://ftpmaster.internal/ubuntu plucky/main ppc64el libattr1 ppc64el 1:2.5.2-2 [12.8 kB] 60s Get:22 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbsd0 ppc64el 0.12.2-2 [57.4 kB] 60s Get:23 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbpf1 ppc64el 1:1.4.6-1 [217 kB] 60s Get:24 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmnl0 ppc64el 1.0.5-3 [13.4 kB] 60s Get:25 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-gi ppc64el 3.50.0-3 [267 kB] 60s Get:26 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.14.0-2 [210 kB] 60s Get:27 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdrm-common all 2.4.123-1 [8436 B] 60s Get:28 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdrm2 ppc64el 2.4.123-1 [49.6 kB] 60s Get:29 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmaxminddb0 ppc64el 1.11.0-1 [21.6 kB] 60s Get:30 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnghttp2-14 ppc64el 1.64.0-1 [89.7 kB] 60s Get:31 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburcu8t64 ppc64el 0.14.1-1 [72.8 kB] 60s Get:32 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2024.10.24-1 [279 kB] 60s Get:33 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12-gdbm ppc64el 3.12.7-2 [30.6 kB] 60s Get:34 http://ftpmaster.internal/ubuntu plucky/main ppc64el usbutils ppc64el 1:018-1 [89.8 kB] 60s Get:35 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-utils3 ppc64el 3.2.1-1 [20.6 kB] 60s Get:36 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-crypto3 ppc64el 3.2.1-1 [24.8 kB] 60s Get:37 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-fs3 ppc64el 3.2.1-1 [40.6 kB] 60s Get:38 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-loop3 ppc64el 3.2.1-1 [7704 B] 60s Get:39 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbytesize1 ppc64el 2.11-1ubuntu1 [14.8 kB] 60s Get:40 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbytesize-common all 2.11-1ubuntu1 [3584 B] 60s Get:41 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-mdraid3 ppc64el 3.2.1-1 [14.2 kB] 60s Get:42 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-nvme3 ppc64el 3.2.1-1 [20.2 kB] 60s Get:43 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-part3 ppc64el 3.2.1-1 [17.5 kB] 60s Get:44 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-swap3 ppc64el 3.2.1-1 [8538 B] 60s Get:45 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev3 ppc64el 3.2.1-1 [54.1 kB] 60s Get:46 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-common all 1.10.0+ds-2 [5502 B] 60s Get:47 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-0 ppc64el 1.10.0+ds-2 [75.9 kB] 60s Get:48 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnspr4 ppc64el 2:4.35-1.1ubuntu2 [134 kB] 60s Get:49 http://ftpmaster.internal/ubuntu plucky/main ppc64el udisks2 ppc64el 2.10.1-11ubuntu1 [344 kB] 60s Get:50 http://ftpmaster.internal/ubuntu plucky/main ppc64el libudisks2-0 ppc64el 2.10.1-11ubuntu1 [180 kB] 60s Get:51 http://ftpmaster.internal/ubuntu plucky/main ppc64el linux-firmware ppc64el 20241108.git3bbb13a7-0ubuntu1 [536 MB] 79s Get:52 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-bcrypt ppc64el 4.2.0-1 [231 kB] 79s Get:53 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-cryptography ppc64el 43.0.0-1 [1005 kB] 79s Get:54 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-idna all 3.8-2 [47.0 kB] 79s Get:55 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-jsonpatch all 1.32-4 [12.2 kB] 79s Get:56 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-wadllib all 2.0.0-1 [36.7 kB] 79s Get:57 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-lazr.restfulclient all 0.14.6-2 [50.9 kB] 79s Get:58 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-urllib3 all 2.0.7-2ubuntu0.1 [93.1 kB] 79s Get:59 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-cryptsetup ppc64el 256.5-2ubuntu4 [127 kB] 80s Preconfiguring packages ... 80s Fetched 561 MB in 21s (27.0 MB/s) 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 ... 73539 files and directories currently installed.) 80s Preparing to unpack .../base-passwd_3.6.5_ppc64el.deb ... 80s Unpacking base-passwd (3.6.5) over (3.6.4) ... 80s Setting up base-passwd (3.6.5) ... 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 ... 73540 files and directories currently installed.) 80s Preparing to unpack .../00-libpython3.12t64_3.12.7-2_ppc64el.deb ... 80s Unpacking libpython3.12t64:ppc64el (3.12.7-2) over (3.12.7-1) ... 80s Preparing to unpack .../01-python3.12_3.12.7-2_ppc64el.deb ... 80s Unpacking python3.12 (3.12.7-2) over (3.12.7-1) ... 80s Preparing to unpack .../02-libpython3.12-stdlib_3.12.7-2_ppc64el.deb ... 80s Unpacking libpython3.12-stdlib:ppc64el (3.12.7-2) over (3.12.7-1) ... 80s Preparing to unpack .../03-python3.12-minimal_3.12.7-2_ppc64el.deb ... 80s Unpacking python3.12-minimal (3.12.7-2) over (3.12.7-1) ... 81s Preparing to unpack .../04-libpython3.12-minimal_3.12.7-2_ppc64el.deb ... 81s Unpacking libpython3.12-minimal:ppc64el (3.12.7-2) over (3.12.7-1) ... 81s Preparing to unpack .../05-tzdata_2024b-1ubuntu2_all.deb ... 81s Unpacking tzdata (2024b-1ubuntu2) over (2024a-4ubuntu1) ... 81s Preparing to unpack .../06-libnss-systemd_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking libnss-systemd:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../07-systemd-timesyncd_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking systemd-timesyncd (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../08-systemd-resolved_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking systemd-resolved (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../09-libsystemd-shared_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking libsystemd-shared:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../10-libsystemd0_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking libsystemd0:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Setting up libsystemd0:ppc64el (256.5-2ubuntu4) ... 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 ... 73539 files and directories currently installed.) 81s Preparing to unpack .../systemd-sysv_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking systemd-sysv (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../libpam-systemd_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking libpam-systemd:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../systemd_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking systemd (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../udev_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking udev (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Preparing to unpack .../libudev1_256.5-2ubuntu4_ppc64el.deb ... 81s Unpacking libudev1:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 81s Setting up libudev1:ppc64el (256.5-2ubuntu4) ... 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 ... 73539 files and directories currently installed.) 81s Preparing to unpack .../openssh-sftp-server_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 81s Unpacking openssh-sftp-server (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 81s Preparing to unpack .../openssh-server_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 81s Unpacking openssh-server (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 82s Preparing to unpack .../openssh-client_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 82s Unpacking openssh-client (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 82s Preparing to unpack .../libattr1_1%3a2.5.2-2_ppc64el.deb ... 82s Unpacking libattr1:ppc64el (1:2.5.2-2) over (1:2.5.2-1build2) ... 82s Setting up libattr1:ppc64el (1:2.5.2-2) ... 82s (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 ... 73539 files and directories currently installed.) 82s Preparing to unpack .../libbsd0_0.12.2-2_ppc64el.deb ... 82s Unpacking libbsd0:ppc64el (0.12.2-2) over (0.12.2-1) ... 82s Setting up libbsd0:ppc64el (0.12.2-2) ... 82s (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 ... 73539 files and directories currently installed.) 82s Preparing to unpack .../00-libbpf1_1%3a1.4.6-1_ppc64el.deb ... 82s Unpacking libbpf1:ppc64el (1:1.4.6-1) over (1:1.4.5-1) ... 82s Preparing to unpack .../01-libmnl0_1.0.5-3_ppc64el.deb ... 82s Unpacking libmnl0:ppc64el (1.0.5-3) over (1.0.5-2build1) ... 82s Preparing to unpack .../02-python3-gi_3.50.0-3_ppc64el.deb ... 82s Unpacking python3-gi (3.50.0-3) over (3.48.2-1) ... 82s Preparing to unpack .../03-bash-completion_1%3a2.14.0-2_all.deb ... 82s Unpacking bash-completion (1:2.14.0-2) over (1:2.14.0-1) ... 82s Preparing to unpack .../04-libdrm-common_2.4.123-1_all.deb ... 82s Unpacking libdrm-common (2.4.123-1) over (2.4.122-1) ... 82s Preparing to unpack .../05-libdrm2_2.4.123-1_ppc64el.deb ... 82s Unpacking libdrm2:ppc64el (2.4.123-1) over (2.4.122-1) ... 82s Preparing to unpack .../06-libmaxminddb0_1.11.0-1_ppc64el.deb ... 82s Unpacking libmaxminddb0:ppc64el (1.11.0-1) over (1.10.0-1) ... 82s Preparing to unpack .../07-libnghttp2-14_1.64.0-1_ppc64el.deb ... 82s Unpacking libnghttp2-14:ppc64el (1.64.0-1) over (1.62.1-2) ... 82s Preparing to unpack .../08-liburcu8t64_0.14.1-1_ppc64el.deb ... 82s Unpacking liburcu8t64:ppc64el (0.14.1-1) over (0.14.0-4) ... 82s Preparing to unpack .../09-pci.ids_0.0~2024.10.24-1_all.deb ... 82s Unpacking pci.ids (0.0~2024.10.24-1) over (0.0~2024.09.12-1) ... 82s Preparing to unpack .../10-python3.12-gdbm_3.12.7-2_ppc64el.deb ... 82s Unpacking python3.12-gdbm (3.12.7-2) over (3.12.7-1) ... 82s Preparing to unpack .../11-usbutils_1%3a018-1_ppc64el.deb ... 82s Unpacking usbutils (1:018-1) over (1:017-3build1) ... 82s Preparing to unpack .../12-libblockdev-utils3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-utils3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../13-libblockdev-crypto3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-crypto3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../14-libblockdev-fs3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-fs3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../15-libblockdev-loop3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-loop3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../16-libbytesize1_2.11-1ubuntu1_ppc64el.deb ... 82s Unpacking libbytesize1:ppc64el (2.11-1ubuntu1) over (2.10-1ubuntu2) ... 82s Preparing to unpack .../17-libbytesize-common_2.11-1ubuntu1_all.deb ... 82s Unpacking libbytesize-common (2.11-1ubuntu1) over (2.10-1ubuntu2) ... 82s Preparing to unpack .../18-libblockdev-mdraid3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-mdraid3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../19-libblockdev-nvme3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-nvme3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../20-libblockdev-part3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-part3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../21-libblockdev-swap3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev-swap3:ppc64el (3.2.1-1) over (3.2.0-2) ... 82s Preparing to unpack .../22-libblockdev3_3.2.1-1_ppc64el.deb ... 82s Unpacking libblockdev3:ppc64el (3.2.1-1) over (3.2.0-2) ... 83s Preparing to unpack .../23-libjson-glib-1.0-common_1.10.0+ds-2_all.deb ... 83s Unpacking libjson-glib-1.0-common (1.10.0+ds-2) over (1.8.0-2build2) ... 83s Preparing to unpack .../24-libjson-glib-1.0-0_1.10.0+ds-2_ppc64el.deb ... 83s Unpacking libjson-glib-1.0-0:ppc64el (1.10.0+ds-2) over (1.8.0-2build2) ... 83s Preparing to unpack .../25-libnspr4_2%3a4.35-1.1ubuntu2_ppc64el.deb ... 83s Unpacking libnspr4:ppc64el (2:4.35-1.1ubuntu2) over (2:4.35-1.1ubuntu1) ... 83s Preparing to unpack .../26-udisks2_2.10.1-11ubuntu1_ppc64el.deb ... 83s Unpacking udisks2 (2.10.1-11ubuntu1) over (2.10.1-9ubuntu2) ... 83s Preparing to unpack .../27-libudisks2-0_2.10.1-11ubuntu1_ppc64el.deb ... 83s Unpacking libudisks2-0:ppc64el (2.10.1-11ubuntu1) over (2.10.1-9ubuntu2) ... 83s Preparing to unpack .../28-linux-firmware_20241108.git3bbb13a7-0ubuntu1_ppc64el.deb ... 83s Unpacking linux-firmware (20241108.git3bbb13a7-0ubuntu1) over (20240913.gita34e7a5f-0ubuntu2) ... 86s Selecting previously unselected package python3-bcrypt. 86s Preparing to unpack .../29-python3-bcrypt_4.2.0-1_ppc64el.deb ... 86s Unpacking python3-bcrypt (4.2.0-1) ... 86s Preparing to unpack .../30-python3-cryptography_43.0.0-1_ppc64el.deb ... 86s Unpacking python3-cryptography (43.0.0-1) over (42.0.5-2build1) ... 86s Preparing to unpack .../31-python3-idna_3.8-2_all.deb ... 86s Unpacking python3-idna (3.8-2) over (3.6-2.1) ... 86s Preparing to unpack .../32-python3-jsonpatch_1.32-4_all.deb ... 86s Unpacking python3-jsonpatch (1.32-4) over (1.32-3) ... 86s Preparing to unpack .../33-python3-wadllib_2.0.0-1_all.deb ... 86s Unpacking python3-wadllib (2.0.0-1) over (1.3.6-5) ... 86s Preparing to unpack .../34-python3-lazr.restfulclient_0.14.6-2_all.deb ... 86s Unpacking python3-lazr.restfulclient (0.14.6-2) over (0.14.6-1) ... 86s Preparing to unpack .../35-python3-urllib3_2.0.7-2ubuntu0.1_all.deb ... 86s Unpacking python3-urllib3 (2.0.7-2ubuntu0.1) over (2.0.7-2) ... 86s Selecting previously unselected package systemd-cryptsetup. 86s Preparing to unpack .../36-systemd-cryptsetup_256.5-2ubuntu4_ppc64el.deb ... 86s Unpacking systemd-cryptsetup (256.5-2ubuntu4) ... 86s Setting up liburcu8t64:ppc64el (0.14.1-1) ... 86s Setting up pci.ids (0.0~2024.10.24-1) ... 86s Setting up linux-firmware (20241108.git3bbb13a7-0ubuntu1) ... 86s Setting up openssh-client (1:9.7p1-7ubuntu5) ... 86s Setting up libmaxminddb0:ppc64el (1.11.0-1) ... 86s Setting up python3.12-gdbm (3.12.7-2) ... 86s Setting up python3-jsonpatch (1.32-4) ... 86s Setting up python3-bcrypt (4.2.0-1) ... 86s Setting up libpython3.12-minimal:ppc64el (3.12.7-2) ... 86s Setting up libnghttp2-14:ppc64el (1.64.0-1) ... 86s Setting up usbutils (1:018-1) ... 86s Setting up tzdata (2024b-1ubuntu2) ... 86s 86s Current default time zone: 'Etc/UTC' 86s Local time is now: Sat Nov 9 11:32:22 UTC 2024. 86s Universal Time is now: Sat Nov 9 11:32:22 UTC 2024. 86s Run 'dpkg-reconfigure tzdata' if you wish to change it. 86s 86s Setting up libnspr4:ppc64el (2:4.35-1.1ubuntu2) ... 86s Setting up bash-completion (1:2.14.0-2) ... 86s Setting up python3-cryptography (43.0.0-1) ... 87s Setting up python3-wadllib (2.0.0-1) ... 87s Setting up python3-gi (3.50.0-3) ... 87s Setting up libbytesize-common (2.11-1ubuntu1) ... 87s Setting up libblockdev-utils3:ppc64el (3.2.1-1) ... 87s Setting up libmnl0:ppc64el (1.0.5-3) ... 87s Setting up python3-idna (3.8-2) ... 87s Setting up libsystemd-shared:ppc64el (256.5-2ubuntu4) ... 87s Setting up python3-urllib3 (2.0.7-2ubuntu0.1) ... 87s Setting up libblockdev-nvme3:ppc64el (3.2.1-1) ... 87s Setting up libblockdev-fs3:ppc64el (3.2.1-1) ... 87s Setting up libjson-glib-1.0-common (1.10.0+ds-2) ... 87s Setting up libbytesize1:ppc64el (2.11-1ubuntu1) ... 87s Setting up python3-lazr.restfulclient (0.14.6-2) ... 87s Setting up libdrm-common (2.4.123-1) ... 87s Setting up libbpf1:ppc64el (1:1.4.6-1) ... 87s Setting up libudisks2-0:ppc64el (2.10.1-11ubuntu1) ... 87s Setting up python3.12-minimal (3.12.7-2) ... 88s Setting up openssh-sftp-server (1:9.7p1-7ubuntu5) ... 88s Setting up libpython3.12-stdlib:ppc64el (3.12.7-2) ... 88s Setting up libblockdev-mdraid3:ppc64el (3.2.1-1) ... 88s Setting up openssh-server (1:9.7p1-7ubuntu5) ... 89s Setting up libblockdev-crypto3:ppc64el (3.2.1-1) ... 89s Setting up libblockdev-swap3:ppc64el (3.2.1-1) ... 89s Setting up python3.12 (3.12.7-2) ... 90s Setting up libblockdev-loop3:ppc64el (3.2.1-1) ... 90s Setting up systemd (256.5-2ubuntu4) ... 90s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 91s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 91s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 91s Setting up libblockdev3:ppc64el (3.2.1-1) ... 91s Setting up libjson-glib-1.0-0:ppc64el (1.10.0+ds-2) ... 91s Setting up libblockdev-part3:ppc64el (3.2.1-1) ... 91s Setting up libpython3.12t64:ppc64el (3.12.7-2) ... 91s Setting up systemd-cryptsetup (256.5-2ubuntu4) ... 91s Setting up systemd-timesyncd (256.5-2ubuntu4) ... 92s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 92s Setting up udev (256.5-2ubuntu4) ... 93s Setting up libdrm2:ppc64el (2.4.123-1) ... 93s Setting up systemd-resolved (256.5-2ubuntu4) ... 93s Setting up udisks2 (2.10.1-11ubuntu1) ... 94s Setting up systemd-sysv (256.5-2ubuntu4) ... 94s Setting up libnss-systemd:ppc64el (256.5-2ubuntu4) ... 94s Setting up libpam-systemd:ppc64el (256.5-2ubuntu4) ... 94s Processing triggers for libc-bin (2.40-1ubuntu3) ... 94s Processing triggers for ufw (0.36.2-8) ... 94s Processing triggers for man-db (2.12.1-3) ... 95s Processing triggers for dbus (1.14.10-4ubuntu5) ... 95s Processing triggers for shared-mime-info (2.4-5) ... 96s Processing triggers for initramfs-tools (0.142ubuntu34) ... 96s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 96s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 102s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 103s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 103s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 103s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 104s Reading package lists... 104s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s Calculating upgrade... 105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s autopkgtest [11:32:41]: rebooting testbed after setup commands that affected boot 109s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 134s autopkgtest [11:33:10]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 137s autopkgtest [11:33:13]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 139s Get:1 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.72-3 (dsc) [2842 B] 139s Get:2 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.72-3 (tar) [901 kB] 139s Get:3 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.72-3 (asc) [833 B] 139s Get:4 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.72-3 (diff) [54.8 kB] 139s gpgv: Signature made Thu Apr 11 10:03:23 2024 UTC 139s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 139s gpgv: issuer "roam@debian.org" 139s gpgv: Can't check signature: No public key 139s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.72-3.dsc: no acceptable signature found 139s autopkgtest [11:33:15]: testing package stunnel4 version 3:5.72-3 139s autopkgtest [11:33:15]: build not needed 140s autopkgtest [11:33:16]: test debian-python: preparing testbed 141s Reading package lists... 141s Building dependency tree... 141s Reading state information... 141s Starting pkgProblemResolver with broken count: 0 141s Starting 2 pkgProblemResolver with broken count: 0 141s Done 141s The following additional packages will be installed: 141s python3-all stunnel4 141s Suggested packages: 141s logcheck-database 141s The following NEW packages will be installed: 141s autopkgtest-satdep python3-all stunnel4 141s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 238 kB/238 kB of archives. 141s After this operation, 725 kB of additional disk space will be used. 141s Get:1 /tmp/autopkgtest.193IuI/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 142s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el stunnel4 ppc64el 3:5.72-3 [237 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-all ppc64el 3.12.6-0ubuntu1 [888 B] 142s Fetched 238 kB in 0s (509 kB/s) 142s Selecting previously unselected package stunnel4. 142s (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 ... 73789 files and directories currently installed.) 142s Preparing to unpack .../stunnel4_3%3a5.72-3_ppc64el.deb ... 142s Unpacking stunnel4 (3:5.72-3) ... 142s Selecting previously unselected package python3-all. 142s Preparing to unpack .../python3-all_3.12.6-0ubuntu1_ppc64el.deb ... 142s Unpacking python3-all (3.12.6-0ubuntu1) ... 143s Selecting previously unselected package autopkgtest-satdep. 143s Preparing to unpack .../1-autopkgtest-satdep.deb ... 143s Unpacking autopkgtest-satdep (0) ... 143s Setting up stunnel4 (3:5.72-3) ... 143s Creating group 'stunnel4' with GID 988. 143s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 143s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 143s Setting up python3-all (3.12.6-0ubuntu1) ... 143s Setting up autopkgtest-satdep (0) ... 143s Processing triggers for man-db (2.12.1-3) ... 146s (Reading database ... 73834 files and directories currently installed.) 146s Removing autopkgtest-satdep (0) ... 147s autopkgtest [11:33:23]: 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 147s autopkgtest [11:33:23]: test debian-python: [----------------------- 147s 147s 147s ====== python3.12 147s 147s Using /tmp/struntime.jekypj0q as a temporary directory 147s Trying to obtain the version of /usr/bin/stunnel4 147s Started `/usr/bin/stunnel4 -version` as process 1642 147s Got stunnel version 5.72 147s [main] Starting to do things 147s [main] Awaiting the 'listener started' event 147s [find_listening_port] Looking for a port to listen on 147s [find_listening_port] Trying localhost port 6502 147s [listener] Telling the main thread about [::1]:6502 147s [listener] Awaiting client connections... 147s [main] Apparently we are listening on [::1]:6502 147s [main] Testing cleartext connections 147s [test_connect [::1]:6502 0] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 1] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 2] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 3] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 4] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 5] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 6] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 7] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 8] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 9] Trying port 6502 encrypted False 147s [test_connect [::1]:6502 0] Opening an unencrypted connection 147s [test_connect [::1]:6502 1] Opening an unencrypted connection 147s [test_connect [::1]:6502 6] Opening an unencrypted connection 147s [test_connect [::1]:6502 7] Opening an unencrypted connection 147s [listener] A client connected from somewhere 147s [[::1]:59590] New connection 147s [[::1]:59590] Telling the main thread 147s [[::1]:59590] Let them tell us something... 147s [listener] A client connected from somewhere 147s [[::1]:59602] New connection 147s [[::1]:59602] Telling the main thread 147s [[::1]:59602] Let them tell us something... 147s [test_connect [::1]:6502 0] Connected to the server: [::1]:59590 147s [test_connect [::1]:6502 1] Connected to the server: [::1]:59602 147s [listener] A client connected from somewhere 147s [[::1]:59612] New connection 147s [[::1]:59612] Telling the main thread 147s [[::1]:59612] Let them tell us something... 147s [listener] A client connected from somewhere 147s [[::1]:59624] New connection 147s [[::1]:59624] Telling the main thread 147s [[::1]:59624] Let them tell us something... 147s [test_connect [::1]:6502 6] Connected to the server: [::1]:59612 147s [test_connect [::1]:6502 7] Connected to the server: [::1]:59624 147s [test_connect [::1]:6502 2] Opening an unencrypted connection 147s [test_connect [::1]:6502 8] Opening an unencrypted connection 147s [listener] A client connected from somewhere 147s [[::1]:59630] New connection 147s [[::1]:59630] Telling the main thread 147s [[::1]:59630] Let them tell us something... 147s [test_connect [::1]:6502 2] Connected to the server: [::1]:59630 147s [listener] A client connected from somewhere 147s [[::1]:59640] New connection 147s [[::1]:59640] Telling the main thread 147s [[::1]:59640] Let them tell us something... 147s [test_connect [::1]:6502 8] Connected to the server: [::1]:59640 148s [test_connect [::1]:6502 4] Opening an unencrypted connection 148s [test_connect [::1]:6502 9] Opening an unencrypted connection 148s [listener] A client connected from somewhere 148s [[::1]:59642] New connection 148s [[::1]:59642] Telling the main thread 148s [[::1]:59642] Let them tell us something... 148s [listener] A client connected from somewhere 148s [[::1]:59654] New connection 148s [[::1]:59654] Telling the main thread 148s [[::1]:59654] Let them tell us something... 148s [test_connect [::1]:6502 4] Connected to the server: [::1]:59642 148s [test_connect [::1]:6502 9] Connected to the server: [::1]:59654 148s [test_connect [::1]:6502 7] Sending something... 148s [test_connect [::1]:6502 7] Waiting for the main thread to figure it out 148s [[::1]:59624] Telling the main thread about b'Hello 7\n' 148s [[::1]:59624] Writing something back... 148s [[::1]:59624] Telling the main thread about b'There!\n' 148s [[::1]:59624] Closing down the writer socket 148s [test_connect [::1]:6502 7] The listener acknowledged [::1]:59624 148s [test_connect [::1]:6502 7] Waiting for the server to receive it... 148s [test_connect [::1]:6502 7] Waiting for the server to send something 148s [test_connect [::1]:6502 7] Trying to receive the actual data 148s [test_connect [::1]:6502 7] Waiting for the server to close the connection 148s [[::1]:59624] Telling the main thread done 148s [test_connect [::1]:6502 7] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 7] Closing our writer socket, too 148s [test_connect [::1]:6502 7] Letting the main thread know we're done 148s [test_connect [::1]:6502 7] Done 148s [process_connections] Fetching task plain-7 148s [process_connections] Waiting for task plain-7 148s [process_connections] Done with task plain-7 148s [test_connect [::1]:6502 0] Sending something... 148s [test_connect [::1]:6502 0] Waiting for the main thread to figure it out 148s [test_connect [::1]:6502 1] Sending something... 148s [test_connect [::1]:6502 1] Waiting for the main thread to figure it out 148s [[::1]:59590] Telling the main thread about b'Hello 0\n' 148s [[::1]:59590] Writing something back... 148s [[::1]:59590] Telling the main thread about b'There!\n' 148s [[::1]:59590] Closing down the writer socket 148s [[::1]:59602] Telling the main thread about b'Hello 1\n' 148s [[::1]:59602] Writing something back... 148s [[::1]:59602] Telling the main thread about b'There!\n' 148s [[::1]:59602] Closing down the writer socket 148s [test_connect [::1]:6502 0] The listener acknowledged [::1]:59590 148s [test_connect [::1]:6502 0] Waiting for the server to receive it... 148s [test_connect [::1]:6502 0] Waiting for the server to send something 148s [test_connect [::1]:6502 0] Trying to receive the actual data 148s [test_connect [::1]:6502 0] Waiting for the server to close the connection 148s [test_connect [::1]:6502 1] The listener acknowledged [::1]:59602 148s [test_connect [::1]:6502 1] Waiting for the server to receive it... 148s [test_connect [::1]:6502 1] Waiting for the server to send something 148s [test_connect [::1]:6502 1] Trying to receive the actual data 148s [test_connect [::1]:6502 1] Waiting for the server to close the connection 148s [[::1]:59590] Telling the main thread done 148s [[::1]:59602] Telling the main thread done 148s [test_connect [::1]:6502 0] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 0] Closing our writer socket, too 148s [test_connect [::1]:6502 1] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 1] Closing our writer socket, too 148s [test_connect [::1]:6502 0] Letting the main thread know we're done 148s [test_connect [::1]:6502 0] Done 148s [test_connect [::1]:6502 1] Letting the main thread know we're done 148s [test_connect [::1]:6502 1] Done 148s [process_connections] Fetching task plain-0 148s [process_connections] Waiting for task plain-0 148s [process_connections] Done with task plain-0 148s [process_connections] Fetching task plain-1 148s [process_connections] Waiting for task plain-1 148s [process_connections] Done with task plain-1 148s [test_connect [::1]:6502 3] Opening an unencrypted connection 148s [listener] A client connected from somewhere 148s [[::1]:59658] New connection 148s [[::1]:59658] Telling the main thread 148s [[::1]:59658] Let them tell us something... 148s [test_connect [::1]:6502 3] Connected to the server: [::1]:59658 148s [test_connect [::1]:6502 6] Sending something... 148s [test_connect [::1]:6502 6] Waiting for the main thread to figure it out 148s [[::1]:59612] Telling the main thread about b'Hello 6\n' 148s [[::1]:59612] Writing something back... 148s [[::1]:59612] Telling the main thread about b'There!\n' 148s [[::1]:59612] Closing down the writer socket 148s [test_connect [::1]:6502 6] The listener acknowledged [::1]:59612 148s [test_connect [::1]:6502 6] Waiting for the server to receive it... 148s [test_connect [::1]:6502 6] Waiting for the server to send something 148s [test_connect [::1]:6502 6] Trying to receive the actual data 148s [test_connect [::1]:6502 6] Waiting for the server to close the connection 148s [[::1]:59612] Telling the main thread done 148s [test_connect [::1]:6502 6] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 6] Closing our writer socket, too 148s [test_connect [::1]:6502 6] Letting the main thread know we're done 148s [test_connect [::1]:6502 6] Done 148s [process_connections] Fetching task plain-6 148s [process_connections] Waiting for task plain-6 148s [process_connections] Done with task plain-6 148s [test_connect [::1]:6502 5] Opening an unencrypted connection 148s [listener] A client connected from somewhere 148s [[::1]:59666] New connection 148s [[::1]:59666] Telling the main thread 148s [[::1]:59666] Let them tell us something... 148s [test_connect [::1]:6502 5] Connected to the server: [::1]:59666 148s [test_connect [::1]:6502 4] Sending something... 148s [test_connect [::1]:6502 4] Waiting for the main thread to figure it out 148s [[::1]:59642] Telling the main thread about b'Hello 4\n' 148s [[::1]:59642] Writing something back... 148s [[::1]:59642] Telling the main thread about b'There!\n' 148s [[::1]:59642] Closing down the writer socket 148s [test_connect [::1]:6502 4] The listener acknowledged [::1]:59642 148s [test_connect [::1]:6502 4] Waiting for the server to receive it... 148s [test_connect [::1]:6502 4] Waiting for the server to send something 148s [test_connect [::1]:6502 4] Trying to receive the actual data 148s [test_connect [::1]:6502 4] Waiting for the server to close the connection 148s [[::1]:59642] Telling the main thread done 148s [test_connect [::1]:6502 4] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 4] Closing our writer socket, too 148s [test_connect [::1]:6502 4] Letting the main thread know we're done 148s [test_connect [::1]:6502 4] Done 148s [process_connections] Fetching task plain-4 148s [process_connections] Waiting for task plain-4 148s [process_connections] Done with task plain-4 148s [test_connect [::1]:6502 8] Sending something... 148s [test_connect [::1]:6502 8] Waiting for the main thread to figure it out 148s [[::1]:59640] Telling the main thread about b'Hello 8\n' 148s [[::1]:59640] Writing something back... 148s [[::1]:59640] Telling the main thread about b'There!\n' 148s [[::1]:59640] Closing down the writer socket 148s [test_connect [::1]:6502 8] The listener acknowledged [::1]:59640 148s [test_connect [::1]:6502 8] Waiting for the server to receive it... 148s [test_connect [::1]:6502 8] Waiting for the server to send something 148s [test_connect [::1]:6502 8] Trying to receive the actual data 148s [test_connect [::1]:6502 8] Waiting for the server to close the connection 148s [[::1]:59640] Telling the main thread done 148s [test_connect [::1]:6502 8] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 8] Closing our writer socket, too 148s [test_connect [::1]:6502 8] Letting the main thread know we're done 148s [test_connect [::1]:6502 8] Done 148s [process_connections] Fetching task plain-8 148s [process_connections] Waiting for task plain-8 148s [process_connections] Done with task plain-8 148s [test_connect [::1]:6502 2] Sending something... 148s [test_connect [::1]:6502 2] Waiting for the main thread to figure it out 148s [[::1]:59630] Telling the main thread about b'Hello 2\n' 148s [[::1]:59630] Writing something back... 148s [[::1]:59630] Telling the main thread about b'There!\n' 148s [[::1]:59630] Closing down the writer socket 148s [test_connect [::1]:6502 2] The listener acknowledged [::1]:59630 148s [test_connect [::1]:6502 2] Waiting for the server to receive it... 148s [test_connect [::1]:6502 2] Waiting for the server to send something 148s [test_connect [::1]:6502 2] Trying to receive the actual data 148s [test_connect [::1]:6502 2] Waiting for the server to close the connection 148s [[::1]:59630] Telling the main thread done 148s [test_connect [::1]:6502 2] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 2] Closing our writer socket, too 148s [test_connect [::1]:6502 2] Letting the main thread know we're done 148s [test_connect [::1]:6502 2] Done 148s [process_connections] Fetching task plain-2 148s [process_connections] Waiting for task plain-2 148s [process_connections] Done with task plain-2 148s [test_connect [::1]:6502 3] Sending something... 148s [test_connect [::1]:6502 3] Waiting for the main thread to figure it out 148s [test_connect [::1]:6502 9] Sending something... 148s [test_connect [::1]:6502 9] Waiting for the main thread to figure it out 148s [[::1]:59658] Telling the main thread about b'Hello 3\n' 148s [[::1]:59658] Writing something back... 148s [[::1]:59658] Telling the main thread about b'There!\n' 148s [[::1]:59658] Closing down the writer socket 148s [[::1]:59654] Telling the main thread about b'Hello 9\n' 148s [[::1]:59654] Writing something back... 148s [[::1]:59654] Telling the main thread about b'There!\n' 148s [[::1]:59654] Closing down the writer socket 148s [test_connect [::1]:6502 3] The listener acknowledged [::1]:59658 148s [test_connect [::1]:6502 3] Waiting for the server to receive it... 148s [test_connect [::1]:6502 3] Waiting for the server to send something 148s [test_connect [::1]:6502 3] Trying to receive the actual data 148s [test_connect [::1]:6502 3] Waiting for the server to close the connection 148s [test_connect [::1]:6502 9] The listener acknowledged [::1]:59654 148s [test_connect [::1]:6502 9] Waiting for the server to receive it... 148s [test_connect [::1]:6502 9] Waiting for the server to send something 148s [test_connect [::1]:6502 9] Trying to receive the actual data 148s [test_connect [::1]:6502 9] Waiting for the server to close the connection 148s [[::1]:59658] Telling the main thread done 148s [[::1]:59654] Telling the main thread done 148s [test_connect [::1]:6502 3] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 3] Closing our writer socket, too 148s [test_connect [::1]:6502 9] Waiting for an EOF on the reader socket 148s [test_connect [::1]:6502 9] Closing our writer socket, too 148s [test_connect [::1]:6502 3] Letting the main thread know we're done 148s [test_connect [::1]:6502 3] Done 148s [test_connect [::1]:6502 9] Letting the main thread know we're done 148s [test_connect [::1]:6502 9] Done 148s [process_connections] Fetching task plain-3 148s [process_connections] Waiting for task plain-3 148s [process_connections] Done with task plain-3 148s [process_connections] Fetching task plain-9 148s [process_connections] Waiting for task plain-9 148s [process_connections] Done with task plain-9 149s [test_connect [::1]:6502 5] Sending something... 149s [test_connect [::1]:6502 5] Waiting for the main thread to figure it out 149s [[::1]:59666] Telling the main thread about b'Hello 5\n' 149s [[::1]:59666] Writing something back... 149s [[::1]:59666] Telling the main thread about b'There!\n' 149s [[::1]:59666] Closing down the writer socket 149s [test_connect [::1]:6502 5] The listener acknowledged [::1]:59666 149s [test_connect [::1]:6502 5] Waiting for the server to receive it... 149s [test_connect [::1]:6502 5] Waiting for the server to send something 149s [test_connect [::1]:6502 5] Trying to receive the actual data 149s [test_connect [::1]:6502 5] Waiting for the server to close the connection 149s [[::1]:59666] Telling the main thread done 149s [test_connect [::1]:6502 5] Waiting for an EOF on the reader socket 149s [test_connect [::1]:6502 5] Closing our writer socket, too 149s [test_connect [::1]:6502 5] Letting the main thread know we're done 149s [test_connect [::1]:6502 5] Done 149s [process_connections] Fetching task plain-5 149s [process_connections] Waiting for task plain-5 149s [process_connections] Done with task plain-5 149s [main] Picking a listen address:port for stunnel 149s [find_listening_port] Looking for a port to listen on 149s [find_listening_port] Trying ::1 port 6503 149s [find_listening_port] got 6503, shutting down the listener 149s [main] Will put stunnel at ::1:6503 149s [main] Preparing the stunnel config file 149s [main] Using config file /tmp/struntime.jekypj0q/stunnel.conf 149s [main] 149s [main] pid = /tmp/struntime.jekypj0q/stunnel.pid 149s [main] foreground = yes 149s [main] 149s [main] cert = /tmp/autopkgtest.193IuI/build.OxG/src/debian/tests/certs/certificate.pem 149s [main] key = /tmp/struntime.jekypj0q/key.pem 149s [main] 149s [main] [test] 149s [main] accept = ::1:6503 149s [main] connect = ::1:6502 149s [main] End of the config file 149s [main] Launching the stunnel server 149s [main] - got pid 1646 149s [main] Launching the stunnel output pipe thread 149s [main] Waiting for stunnel to start up 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: stunnel 5.72 on powerpc64le-unknown-linux-gnu platform' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Compiled with OpenSSL 3.0.13 30 Jan 2024' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Running with OpenSSL 3.3.1 4 Jun 2024' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Update OpenSSL shared libraries or rebuild stunnel' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Reading configuration from file /tmp/struntime.jekypj0q/stunnel.conf' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: UTF-8 byte order mark not detected' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: FIPS mode disabled' 149s [stunnel_output] Read '2024.11.09 11:33:25 LOG5[ui]: Configuration successful' 149s [stunnel_output] The main thread ought to know 149s [main] Testing the encrypted connections 149s [test_connect [::1]:6503 0] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 1] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 2] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 3] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 4] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 5] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 6] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 7] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 8] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 9] Trying port 6503 encrypted True 149s [test_connect [::1]:6503 9] Creating an SSL context 149s [test_connect [::1]:6503 9] - cert required 149s [test_connect [::1]:6503 9] - load_verify_locations() 149s [test_connect [::1]:6503 9] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[0]: Service [test] accepted connection from ::1:46524' 149s [test_connect [::1]:6503 9] Connected to the server: [::1]:46524 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[0]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[0]: Service [test] connected remote server from ::1:42772' 149s [listener] A client connected from somewhere 149s [[::1]:42772] New connection 149s [[::1]:42772] Telling the main thread 149s [[::1]:42772] Let them tell us something... 149s [test_connect [::1]:6503 0] Creating an SSL context 149s [test_connect [::1]:6503 0] - cert required 149s [test_connect [::1]:6503 0] - load_verify_locations() 149s [test_connect [::1]:6503 0] Opening an SSL connection 149s [test_connect [::1]:6503 4] Creating an SSL context 149s [test_connect [::1]:6503 4] - cert required 149s [test_connect [::1]:6503 4] - load_verify_locations() 149s [test_connect [::1]:6503 4] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[1]: Service [test] accepted connection from ::1:46528' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[2]: Service [test] accepted connection from ::1:46544' 149s [test_connect [::1]:6503 4] Connected to the server: [::1]:46544 149s [test_connect [::1]:6503 0] Connected to the server: [::1]:46528 149s [listener] A client connected from somewhere 149s [[::1]:42788] New connection 149s [[::1]:42788] Telling the main thread 149s [[::1]:42788] Let them tell us something... 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[2]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[1]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[1]: Service [test] connected remote server from ::1:42792' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[2]: Service [test] connected remote server from ::1:42788' 149s [listener] A client connected from somewhere 149s [[::1]:42792] New connection 149s [[::1]:42792] Telling the main thread 149s [[::1]:42792] Let them tell us something... 149s [test_connect [::1]:6503 8] Creating an SSL context 149s [test_connect [::1]:6503 8] - cert required 149s [test_connect [::1]:6503 8] - load_verify_locations() 149s [test_connect [::1]:6503 8] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[3]: Service [test] accepted connection from ::1:46560' 149s [test_connect [::1]:6503 8] Connected to the server: [::1]:46560 149s [listener] A client connected from somewhere 149s [[::1]:42798] New connection 149s [[::1]:42798] Telling the main thread 149s [[::1]:42798] Let them tell us something... 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[3]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[3]: Service [test] connected remote server from ::1:42798' 149s [test_connect [::1]:6503 3] Creating an SSL context 149s [test_connect [::1]:6503 3] - cert required 149s [test_connect [::1]:6503 3] - load_verify_locations() 149s [test_connect [::1]:6503 3] Opening an SSL connection 149s [test_connect [::1]:6503 6] Creating an SSL context 149s [test_connect [::1]:6503 6] - cert required 149s [test_connect [::1]:6503 6] - load_verify_locations() 149s [test_connect [::1]:6503 6] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[4]: Service [test] accepted connection from ::1:46574' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[5]: Service [test] accepted connection from ::1:46584' 149s [test_connect [::1]:6503 6] Connected to the server: [::1]:46584 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[5]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[5]: Service [test] connected remote server from ::1:42812' 149s [test_connect [::1]:6503 3] Connected to the server: [::1]:46574 149s [listener] A client connected from somewhere 149s [[::1]:42812] New connection 149s [[::1]:42812] Telling the main thread 149s [[::1]:42812] Let them tell us something... 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[4]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[4]: Service [test] connected remote server from ::1:42824' 149s [listener] A client connected from somewhere 149s [[::1]:42824] New connection 149s [[::1]:42824] Telling the main thread 149s [[::1]:42824] Let them tell us something... 149s [test_connect [::1]:6503 2] Creating an SSL context 149s [test_connect [::1]:6503 2] - cert required 149s [test_connect [::1]:6503 2] - load_verify_locations() 149s [test_connect [::1]:6503 2] Opening an SSL connection 149s [test_connect [::1]:6503 7] Creating an SSL context 149s [test_connect [::1]:6503 7] - cert required 149s [test_connect [::1]:6503 7] - load_verify_locations() 149s [test_connect [::1]:6503 7] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[6]: Service [test] accepted connection from ::1:46594' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[7]: Service [test] accepted connection from ::1:46606' 149s [test_connect [::1]:6503 7] Connected to the server: [::1]:46606 149s [listener] A client connected from somewhere 149s [[::1]:42832] New connection 149s [[::1]:42832] Telling the main thread 149s [[::1]:42832] Let them tell us something... 149s [test_connect [::1]:6503 2] Connected to the server: [::1]:46594 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[7]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[7]: Service [test] connected remote server from ::1:42832' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[6]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[6]: Service [test] connected remote server from ::1:42836' 149s [listener] A client connected from somewhere 149s [[::1]:42836] New connection 149s [[::1]:42836] Telling the main thread 149s [[::1]:42836] Let them tell us something... 149s [test_connect [::1]:6503 5] Creating an SSL context 149s [test_connect [::1]:6503 5] - cert required 149s [test_connect [::1]:6503 5] - load_verify_locations() 149s [test_connect [::1]:6503 5] Opening an SSL connection 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[8]: Service [test] accepted connection from ::1:46610' 149s [test_connect [::1]:6503 5] Connected to the server: [::1]:46610 149s [listener] A client connected from somewhere 149s [[::1]:42844] New connection 149s [[::1]:42844] Telling the main thread 149s [[::1]:42844] Let them tell us something... 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[8]: s_connect: connected ::1:6502' 149s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[8]: Service [test] connected remote server from ::1:42844' 150s [test_connect [::1]:6503 4] Sending something... 150s [test_connect [::1]:6503 4] Waiting for the main thread to figure it out 150s [[::1]:42788] Telling the main thread about b'Hello 4\n' 150s [[::1]:42788] Writing something back... 150s [[::1]:42788] Telling the main thread about b'There!\n' 150s [[::1]:42788] Closing down the writer socket 150s [test_connect [::1]:6503 4] The listener acknowledged [::1]:42788 150s [test_connect [::1]:6503 4] Waiting for the server to receive it... 150s [test_connect [::1]:6503 4] Waiting for the server to send something 150s [test_connect [::1]:6503 4] Trying to receive the actual data 150s [test_connect [::1]:6503 4] Waiting for the server to close the connection 150s [[::1]:42788] Telling the main thread done 150s [test_connect [::1]:6503 4] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 4] Closing our writer socket, too 150s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 4] Letting the main thread know we're done 150s [test_connect [::1]:6503 4] Done 150s [process_connections] Fetching task encr-4 150s [process_connections] Waiting for task encr-4 150s [process_connections] Done with task encr-4 150s [test_connect [::1]:6503 1] Creating an SSL context 150s [test_connect [::1]:6503 1] - cert required 150s [test_connect [::1]:6503 1] - load_verify_locations() 150s [test_connect [::1]:6503 1] Opening an SSL connection 150s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[9]: Service [test] accepted connection from ::1:46620' 150s [test_connect [::1]:6503 1] Connected to the server: [::1]:46620 150s [test_connect [::1]:6503 0] Sending something... 150s [test_connect [::1]:6503 0] Waiting for the main thread to figure it out 150s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[9]: s_connect: connected ::1:6502' 150s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[9]: Service [test] connected remote server from ::1:42858' 150s [listener] A client connected from somewhere 150s [[::1]:42858] New connection 150s [[::1]:42858] Telling the main thread 150s [[::1]:42858] Let them tell us something... 150s [[::1]:42792] Telling the main thread about b'Hello 0\n' 150s [[::1]:42792] Writing something back... 150s [[::1]:42792] Telling the main thread about b'There!\n' 150s [[::1]:42792] Closing down the writer socket 150s [test_connect [::1]:6503 0] The listener acknowledged [::1]:42792 150s [test_connect [::1]:6503 0] Waiting for the server to receive it... 150s [test_connect [::1]:6503 0] Waiting for the server to send something 150s [test_connect [::1]:6503 0] Trying to receive the actual data 150s [test_connect [::1]:6503 0] Waiting for the server to close the connection 150s [[::1]:42792] Telling the main thread done 150s [test_connect [::1]:6503 0] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 0] Closing our writer socket, too 150s [stunnel_output] Read '2024.11.09 11:33:26 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 0] Letting the main thread know we're done 150s [test_connect [::1]:6503 0] Done 150s [process_connections] Fetching task encr-0 150s [process_connections] Waiting for task encr-0 150s [process_connections] Done with task encr-0 150s [test_connect [::1]:6503 7] Sending something... 150s [test_connect [::1]:6503 7] Waiting for the main thread to figure it out 150s [[::1]:42832] Telling the main thread about b'Hello 7\n' 150s [[::1]:42832] Writing something back... 150s [[::1]:42832] Telling the main thread about b'There!\n' 150s [[::1]:42832] Closing down the writer socket 150s [test_connect [::1]:6503 7] The listener acknowledged [::1]:42832 150s [test_connect [::1]:6503 7] Waiting for the server to receive it... 150s [test_connect [::1]:6503 7] Waiting for the server to send something 150s [test_connect [::1]:6503 7] Trying to receive the actual data 150s [test_connect [::1]:6503 7] Waiting for the server to close the connection 150s [[::1]:42832] Telling the main thread done 150s [test_connect [::1]:6503 7] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 7] Closing our writer socket, too 150s [test_connect [::1]:6503 7] Letting the main thread know we're done 150s [test_connect [::1]:6503 7] Done 150s [process_connections] Fetching task encr-7 150s [process_connections] Waiting for task encr-7 150s [process_connections] Done with task encr-7 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 9] Sending something... 150s [test_connect [::1]:6503 9] Waiting for the main thread to figure it out 150s [[::1]:42772] Telling the main thread about b'Hello 9\n' 150s [[::1]:42772] Writing something back... 150s [[::1]:42772] Telling the main thread about b'There!\n' 150s [[::1]:42772] Closing down the writer socket 150s [test_connect [::1]:6503 9] The listener acknowledged [::1]:42772 150s [test_connect [::1]:6503 9] Waiting for the server to receive it... 150s [test_connect [::1]:6503 9] Waiting for the server to send something 150s [test_connect [::1]:6503 9] Trying to receive the actual data 150s [test_connect [::1]:6503 9] Waiting for the server to close the connection 150s [[::1]:42772] Telling the main thread done 150s [test_connect [::1]:6503 9] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 9] Closing our writer socket, too 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 9] Letting the main thread know we're done 150s [test_connect [::1]:6503 9] Done 150s [process_connections] Fetching task encr-9 150s [process_connections] Waiting for task encr-9 150s [process_connections] Done with task encr-9 150s [test_connect [::1]:6503 2] Sending something... 150s [test_connect [::1]:6503 2] Waiting for the main thread to figure it out 150s [[::1]:42836] Telling the main thread about b'Hello 2\n' 150s [[::1]:42836] Writing something back... 150s [[::1]:42836] Telling the main thread about b'There!\n' 150s [[::1]:42836] Closing down the writer socket 150s [test_connect [::1]:6503 2] The listener acknowledged [::1]:42836 150s [test_connect [::1]:6503 2] Waiting for the server to receive it... 150s [test_connect [::1]:6503 2] Waiting for the server to send something 150s [test_connect [::1]:6503 2] Trying to receive the actual data 150s [[::1]:42836] Telling the main thread done 150s [test_connect [::1]:6503 2] Waiting for the server to close the connection 150s [test_connect [::1]:6503 2] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 2] Closing our writer socket, too 150s [test_connect [::1]:6503 3] Sending something... 150s [test_connect [::1]:6503 3] Waiting for the main thread to figure it out 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 2] Letting the main thread know we're done 150s [test_connect [::1]:6503 2] Done 150s [process_connections] Fetching task encr-2 150s [process_connections] Waiting for task encr-2 150s [process_connections] Done with task encr-2 150s [[::1]:42824] Telling the main thread about b'Hello 3\n' 150s [[::1]:42824] Writing something back... 150s [[::1]:42824] Telling the main thread about b'There!\n' 150s [[::1]:42824] Closing down the writer socket 150s [test_connect [::1]:6503 3] The listener acknowledged [::1]:42824 150s [test_connect [::1]:6503 3] Waiting for the server to receive it... 150s [test_connect [::1]:6503 3] Waiting for the server to send something 150s [test_connect [::1]:6503 3] Trying to receive the actual data 150s [[::1]:42824] Telling the main thread done 150s [test_connect [::1]:6503 3] Waiting for the server to close the connection 150s [test_connect [::1]:6503 3] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 3] Closing our writer socket, too 150s [test_connect [::1]:6503 3] Letting the main thread know we're done 150s [test_connect [::1]:6503 3] Done 150s [process_connections] Fetching task encr-3 150s [process_connections] Waiting for task encr-3 150s [process_connections] Done with task encr-3 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 6] Sending something... 150s [test_connect [::1]:6503 6] Waiting for the main thread to figure it out 150s [[::1]:42812] Telling the main thread about b'Hello 6\n' 150s [[::1]:42812] Writing something back... 150s [[::1]:42812] Telling the main thread about b'There!\n' 150s [[::1]:42812] Closing down the writer socket 150s [test_connect [::1]:6503 6] The listener acknowledged [::1]:42812 150s [test_connect [::1]:6503 6] Waiting for the server to receive it... 150s [test_connect [::1]:6503 6] Waiting for the server to send something 150s [test_connect [::1]:6503 6] Trying to receive the actual data 150s [[::1]:42812] Telling the main thread done 150s [test_connect [::1]:6503 6] Waiting for the server to close the connection 150s [test_connect [::1]:6503 6] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 6] Closing our writer socket, too 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 6] Letting the main thread know we're done 150s [test_connect [::1]:6503 6] Done 150s [process_connections] Fetching task encr-6 150s [process_connections] Waiting for task encr-6 150s [process_connections] Done with task encr-6 150s [test_connect [::1]:6503 8] Sending something... 150s [test_connect [::1]:6503 8] Waiting for the main thread to figure it out 150s [[::1]:42798] Telling the main thread about b'Hello 8\n' 150s [[::1]:42798] Writing something back... 150s [[::1]:42798] Telling the main thread about b'There!\n' 150s [[::1]:42798] Closing down the writer socket 150s [test_connect [::1]:6503 8] The listener acknowledged [::1]:42798 150s [test_connect [::1]:6503 8] Waiting for the server to receive it... 150s [test_connect [::1]:6503 8] Waiting for the server to send something 150s [test_connect [::1]:6503 8] Trying to receive the actual data 150s [test_connect [::1]:6503 8] Waiting for the server to close the connection 150s [[::1]:42798] Telling the main thread done 150s [test_connect [::1]:6503 8] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 8] Closing our writer socket, too 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 150s [test_connect [::1]:6503 8] Letting the main thread know we're done 150s [test_connect [::1]:6503 8] Done 150s [process_connections] Fetching task encr-8 150s [process_connections] Waiting for task encr-8 150s [process_connections] Done with task encr-8 150s [test_connect [::1]:6503 5] Sending something... 150s [test_connect [::1]:6503 5] Waiting for the main thread to figure it out 150s [[::1]:42844] Telling the main thread about b'Hello 5\n' 150s [[::1]:42844] Writing something back... 150s [[::1]:42844] Telling the main thread about b'There!\n' 150s [[::1]:42844] Closing down the writer socket 150s [test_connect [::1]:6503 5] The listener acknowledged [::1]:42844 150s [test_connect [::1]:6503 5] Waiting for the server to receive it... 150s [test_connect [::1]:6503 5] Waiting for the server to send something 150s [test_connect [::1]:6503 5] Trying to receive the actual data 150s [test_connect [::1]:6503 5] Waiting for the server to close the connection 150s [[::1]:42844] Telling the main thread done 150s [test_connect [::1]:6503 5] Waiting for an EOF on the reader socket 150s [test_connect [::1]:6503 5] Closing our writer socket, too 150s [test_connect [::1]:6503 5] Letting the main thread know we're done 150s [test_connect [::1]:6503 5] Done 150s [process_connections] Fetching task encr-5 150s [process_connections] Waiting for task encr-5 150s [process_connections] Done with task encr-5 150s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[8]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 151s [test_connect [::1]:6503 1] Sending something... 151s [test_connect [::1]:6503 1] Waiting for the main thread to figure it out 151s [[::1]:42858] Telling the main thread about b'Hello 1\n' 151s [[::1]:42858] Writing something back... 151s [[::1]:42858] Telling the main thread about b'There!\n' 151s [[::1]:42858] Closing down the writer socket 151s [test_connect [::1]:6503 1] The listener acknowledged [::1]:42858 151s [test_connect [::1]:6503 1] Waiting for the server to receive it... 151s [test_connect [::1]:6503 1] Waiting for the server to send something 151s [test_connect [::1]:6503 1] Trying to receive the actual data 151s [[::1]:42858] Telling the main thread done 151s [test_connect [::1]:6503 1] Waiting for the server to close the connection 151s [test_connect [::1]:6503 1] Waiting for an EOF on the reader socket 151s [test_connect [::1]:6503 1] Closing our writer socket, too 151s [stunnel_output] Read '2024.11.09 11:33:27 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 151s [test_connect [::1]:6503 1] Letting the main thread know we're done 151s [test_connect [::1]:6503 1] Done 151s [process_connections] Fetching task encr-1 151s [process_connections] Waiting for task encr-1 151s [process_connections] Done with task encr-1 151s [main] Everything seems to be all right! 151s About to cancel 2 remaining task(s) 151s - listen 151s - output 151s Waiting for the tasks to hopefully finish 151s About to kill and wait for 1 child process(es) 151s - pid 1646 151s Waiting for the processes to exit... 151s Got processes' exit status: [-9] 151s autopkgtest [11:33:27]: test debian-python: -----------------------] 151s debian-python PASS 151s autopkgtest [11:33:27]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 152s autopkgtest [11:33:28]: test upstream: preparing testbed 213s autopkgtest [11:34:29]: testbed dpkg architecture: ppc64el 213s autopkgtest [11:34:29]: testbed apt version: 2.9.8 213s autopkgtest [11:34:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 214s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 214s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 214s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1055 kB] 214s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.4 kB] 214s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [107 kB] 214s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [135 kB] 214s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [757 kB] 214s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [21.3 kB] 215s Fetched 2174 kB in 1s (2195 kB/s) 215s Reading package lists... 217s Reading package lists... 217s Building dependency tree... 217s Reading state information... 217s Calculating upgrade... 217s The following NEW packages will be installed: 217s python3-bcrypt systemd-cryptsetup 217s The following packages will be upgraded: 217s base-passwd bash-completion libattr1 libblockdev-crypto3 libblockdev-fs3 217s libblockdev-loop3 libblockdev-mdraid3 libblockdev-nvme3 libblockdev-part3 217s libblockdev-swap3 libblockdev-utils3 libblockdev3 libbpf1 libbsd0 217s libbytesize-common libbytesize1 libdrm-common libdrm2 libjson-glib-1.0-0 217s libjson-glib-1.0-common libmaxminddb0 libmnl0 libnghttp2-14 libnspr4 217s libnss-systemd libpam-systemd libpython3.12-minimal libpython3.12-stdlib 217s libpython3.12t64 libsystemd-shared libsystemd0 libudev1 libudisks2-0 217s liburcu8t64 linux-firmware openssh-client openssh-server openssh-sftp-server 217s pci.ids python3-cryptography python3-gi python3-idna python3-jsonpatch 217s python3-lazr.restfulclient python3-urllib3 python3-wadllib python3.12 217s python3.12-gdbm python3.12-minimal systemd systemd-resolved systemd-sysv 217s systemd-timesyncd tzdata udev udisks2 usbutils 217s 57 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 217s Need to get 561 MB of archives. 217s After this operation, 28.2 MB of additional disk space will be used. 217s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el base-passwd ppc64el 3.6.5 [55.1 kB] 218s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12t64 ppc64el 3.12.7-2 [2565 kB] 218s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12 ppc64el 3.12.7-2 [661 kB] 218s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12-stdlib ppc64el 3.12.7-2 [2121 kB] 218s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12-minimal ppc64el 3.12.7-2 [2467 kB] 218s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpython3.12-minimal ppc64el 3.12.7-2 [839 kB] 218s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el tzdata all 2024b-1ubuntu2 [274 kB] 218s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnss-systemd ppc64el 256.5-2ubuntu4 [215 kB] 218s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-timesyncd ppc64el 256.5-2ubuntu4 [42.5 kB] 218s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-resolved ppc64el 256.5-2ubuntu4 [368 kB] 218s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsystemd-shared ppc64el 256.5-2ubuntu4 [2503 kB] 218s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsystemd0 ppc64el 256.5-2ubuntu4 [565 kB] 218s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-sysv ppc64el 256.5-2ubuntu4 [11.9 kB] 218s Get:14 http://ftpmaster.internal/ubuntu plucky/main ppc64el libpam-systemd ppc64el 256.5-2ubuntu4 [326 kB] 219s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd ppc64el 256.5-2ubuntu4 [3746 kB] 219s Get:16 http://ftpmaster.internal/ubuntu plucky/main ppc64el udev ppc64el 256.5-2ubuntu4 [2141 kB] 219s Get:17 http://ftpmaster.internal/ubuntu plucky/main ppc64el libudev1 ppc64el 256.5-2ubuntu4 [224 kB] 219s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-sftp-server ppc64el 1:9.7p1-7ubuntu5 [43.3 kB] 219s Get:19 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-server ppc64el 1:9.7p1-7ubuntu5 [629 kB] 219s Get:20 http://ftpmaster.internal/ubuntu plucky/main ppc64el openssh-client ppc64el 1:9.7p1-7ubuntu5 [1105 kB] 219s Get:21 http://ftpmaster.internal/ubuntu plucky/main ppc64el libattr1 ppc64el 1:2.5.2-2 [12.8 kB] 219s Get:22 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbsd0 ppc64el 0.12.2-2 [57.4 kB] 219s Get:23 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbpf1 ppc64el 1:1.4.6-1 [217 kB] 219s Get:24 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmnl0 ppc64el 1.0.5-3 [13.4 kB] 219s Get:25 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-gi ppc64el 3.50.0-3 [267 kB] 219s Get:26 http://ftpmaster.internal/ubuntu plucky/main ppc64el bash-completion all 1:2.14.0-2 [210 kB] 219s Get:27 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdrm-common all 2.4.123-1 [8436 B] 219s Get:28 http://ftpmaster.internal/ubuntu plucky/main ppc64el libdrm2 ppc64el 2.4.123-1 [49.6 kB] 219s Get:29 http://ftpmaster.internal/ubuntu plucky/main ppc64el libmaxminddb0 ppc64el 1.11.0-1 [21.6 kB] 219s Get:30 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnghttp2-14 ppc64el 1.64.0-1 [89.7 kB] 219s Get:31 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburcu8t64 ppc64el 0.14.1-1 [72.8 kB] 219s Get:32 http://ftpmaster.internal/ubuntu plucky/main ppc64el pci.ids all 0.0~2024.10.24-1 [279 kB] 219s Get:33 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12-gdbm ppc64el 3.12.7-2 [30.6 kB] 219s Get:34 http://ftpmaster.internal/ubuntu plucky/main ppc64el usbutils ppc64el 1:018-1 [89.8 kB] 219s Get:35 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-utils3 ppc64el 3.2.1-1 [20.6 kB] 219s Get:36 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-crypto3 ppc64el 3.2.1-1 [24.8 kB] 219s Get:37 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-fs3 ppc64el 3.2.1-1 [40.6 kB] 219s Get:38 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-loop3 ppc64el 3.2.1-1 [7704 B] 219s Get:39 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbytesize1 ppc64el 2.11-1ubuntu1 [14.8 kB] 219s Get:40 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbytesize-common all 2.11-1ubuntu1 [3584 B] 219s Get:41 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-mdraid3 ppc64el 3.2.1-1 [14.2 kB] 219s Get:42 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-nvme3 ppc64el 3.2.1-1 [20.2 kB] 219s Get:43 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-part3 ppc64el 3.2.1-1 [17.5 kB] 219s Get:44 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-swap3 ppc64el 3.2.1-1 [8538 B] 219s Get:45 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev3 ppc64el 3.2.1-1 [54.1 kB] 219s Get:46 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-common all 1.10.0+ds-2 [5502 B] 219s Get:47 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-0 ppc64el 1.10.0+ds-2 [75.9 kB] 219s Get:48 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnspr4 ppc64el 2:4.35-1.1ubuntu2 [134 kB] 219s Get:49 http://ftpmaster.internal/ubuntu plucky/main ppc64el udisks2 ppc64el 2.10.1-11ubuntu1 [344 kB] 219s Get:50 http://ftpmaster.internal/ubuntu plucky/main ppc64el libudisks2-0 ppc64el 2.10.1-11ubuntu1 [180 kB] 219s Get:51 http://ftpmaster.internal/ubuntu plucky/main ppc64el linux-firmware ppc64el 20241108.git3bbb13a7-0ubuntu1 [536 MB] 247s Get:52 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-bcrypt ppc64el 4.2.0-1 [231 kB] 247s Get:53 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-cryptography ppc64el 43.0.0-1 [1005 kB] 247s Get:54 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-idna all 3.8-2 [47.0 kB] 247s Get:55 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-jsonpatch all 1.32-4 [12.2 kB] 247s Get:56 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-wadllib all 2.0.0-1 [36.7 kB] 247s Get:57 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-lazr.restfulclient all 0.14.6-2 [50.9 kB] 247s Get:58 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-urllib3 all 2.0.7-2ubuntu0.1 [93.1 kB] 247s Get:59 http://ftpmaster.internal/ubuntu plucky/main ppc64el systemd-cryptsetup ppc64el 256.5-2ubuntu4 [127 kB] 248s Preconfiguring packages ... 248s Fetched 561 MB in 30s (18.6 MB/s) 248s (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 ... 73539 files and directories currently installed.) 248s Preparing to unpack .../base-passwd_3.6.5_ppc64el.deb ... 248s Unpacking base-passwd (3.6.5) over (3.6.4) ... 248s Setting up base-passwd (3.6.5) ... 248s (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 ... 73540 files and directories currently installed.) 248s Preparing to unpack .../00-libpython3.12t64_3.12.7-2_ppc64el.deb ... 248s Unpacking libpython3.12t64:ppc64el (3.12.7-2) over (3.12.7-1) ... 248s Preparing to unpack .../01-python3.12_3.12.7-2_ppc64el.deb ... 248s Unpacking python3.12 (3.12.7-2) over (3.12.7-1) ... 248s Preparing to unpack .../02-libpython3.12-stdlib_3.12.7-2_ppc64el.deb ... 248s Unpacking libpython3.12-stdlib:ppc64el (3.12.7-2) over (3.12.7-1) ... 249s Preparing to unpack .../03-python3.12-minimal_3.12.7-2_ppc64el.deb ... 249s Unpacking python3.12-minimal (3.12.7-2) over (3.12.7-1) ... 249s Preparing to unpack .../04-libpython3.12-minimal_3.12.7-2_ppc64el.deb ... 249s Unpacking libpython3.12-minimal:ppc64el (3.12.7-2) over (3.12.7-1) ... 249s Preparing to unpack .../05-tzdata_2024b-1ubuntu2_all.deb ... 249s Unpacking tzdata (2024b-1ubuntu2) over (2024a-4ubuntu1) ... 249s Preparing to unpack .../06-libnss-systemd_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking libnss-systemd:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../07-systemd-timesyncd_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking systemd-timesyncd (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../08-systemd-resolved_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking systemd-resolved (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../09-libsystemd-shared_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking libsystemd-shared:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../10-libsystemd0_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking libsystemd0:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Setting up libsystemd0:ppc64el (256.5-2ubuntu4) ... 249s (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 ... 73539 files and directories currently installed.) 249s Preparing to unpack .../systemd-sysv_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking systemd-sysv (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../libpam-systemd_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking libpam-systemd:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../systemd_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking systemd (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../udev_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking udev (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Preparing to unpack .../libudev1_256.5-2ubuntu4_ppc64el.deb ... 249s Unpacking libudev1:ppc64el (256.5-2ubuntu4) over (256.5-2ubuntu3) ... 249s Setting up libudev1:ppc64el (256.5-2ubuntu4) ... 250s (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 ... 73539 files and directories currently installed.) 250s Preparing to unpack .../openssh-sftp-server_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 250s Unpacking openssh-sftp-server (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 250s Preparing to unpack .../openssh-server_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 250s Unpacking openssh-server (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 250s Preparing to unpack .../openssh-client_1%3a9.7p1-7ubuntu5_ppc64el.deb ... 250s Unpacking openssh-client (1:9.7p1-7ubuntu5) over (1:9.7p1-7ubuntu4) ... 250s Preparing to unpack .../libattr1_1%3a2.5.2-2_ppc64el.deb ... 250s Unpacking libattr1:ppc64el (1:2.5.2-2) over (1:2.5.2-1build2) ... 250s Setting up libattr1:ppc64el (1:2.5.2-2) ... 250s (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 ... 73539 files and directories currently installed.) 250s Preparing to unpack .../libbsd0_0.12.2-2_ppc64el.deb ... 250s Unpacking libbsd0:ppc64el (0.12.2-2) over (0.12.2-1) ... 250s Setting up libbsd0:ppc64el (0.12.2-2) ... 250s (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 ... 73539 files and directories currently installed.) 250s Preparing to unpack .../00-libbpf1_1%3a1.4.6-1_ppc64el.deb ... 250s Unpacking libbpf1:ppc64el (1:1.4.6-1) over (1:1.4.5-1) ... 250s Preparing to unpack .../01-libmnl0_1.0.5-3_ppc64el.deb ... 250s Unpacking libmnl0:ppc64el (1.0.5-3) over (1.0.5-2build1) ... 250s Preparing to unpack .../02-python3-gi_3.50.0-3_ppc64el.deb ... 250s Unpacking python3-gi (3.50.0-3) over (3.48.2-1) ... 250s Preparing to unpack .../03-bash-completion_1%3a2.14.0-2_all.deb ... 250s Unpacking bash-completion (1:2.14.0-2) over (1:2.14.0-1) ... 250s Preparing to unpack .../04-libdrm-common_2.4.123-1_all.deb ... 250s Unpacking libdrm-common (2.4.123-1) over (2.4.122-1) ... 250s Preparing to unpack .../05-libdrm2_2.4.123-1_ppc64el.deb ... 250s Unpacking libdrm2:ppc64el (2.4.123-1) over (2.4.122-1) ... 250s Preparing to unpack .../06-libmaxminddb0_1.11.0-1_ppc64el.deb ... 250s Unpacking libmaxminddb0:ppc64el (1.11.0-1) over (1.10.0-1) ... 250s Preparing to unpack .../07-libnghttp2-14_1.64.0-1_ppc64el.deb ... 250s Unpacking libnghttp2-14:ppc64el (1.64.0-1) over (1.62.1-2) ... 250s Preparing to unpack .../08-liburcu8t64_0.14.1-1_ppc64el.deb ... 250s Unpacking liburcu8t64:ppc64el (0.14.1-1) over (0.14.0-4) ... 250s Preparing to unpack .../09-pci.ids_0.0~2024.10.24-1_all.deb ... 250s Unpacking pci.ids (0.0~2024.10.24-1) over (0.0~2024.09.12-1) ... 250s Preparing to unpack .../10-python3.12-gdbm_3.12.7-2_ppc64el.deb ... 250s Unpacking python3.12-gdbm (3.12.7-2) over (3.12.7-1) ... 250s Preparing to unpack .../11-usbutils_1%3a018-1_ppc64el.deb ... 250s Unpacking usbutils (1:018-1) over (1:017-3build1) ... 251s Preparing to unpack .../12-libblockdev-utils3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-utils3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../13-libblockdev-crypto3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-crypto3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../14-libblockdev-fs3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-fs3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../15-libblockdev-loop3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-loop3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../16-libbytesize1_2.11-1ubuntu1_ppc64el.deb ... 251s Unpacking libbytesize1:ppc64el (2.11-1ubuntu1) over (2.10-1ubuntu2) ... 251s Preparing to unpack .../17-libbytesize-common_2.11-1ubuntu1_all.deb ... 251s Unpacking libbytesize-common (2.11-1ubuntu1) over (2.10-1ubuntu2) ... 251s Preparing to unpack .../18-libblockdev-mdraid3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-mdraid3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../19-libblockdev-nvme3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-nvme3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../20-libblockdev-part3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-part3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../21-libblockdev-swap3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev-swap3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../22-libblockdev3_3.2.1-1_ppc64el.deb ... 251s Unpacking libblockdev3:ppc64el (3.2.1-1) over (3.2.0-2) ... 251s Preparing to unpack .../23-libjson-glib-1.0-common_1.10.0+ds-2_all.deb ... 251s Unpacking libjson-glib-1.0-common (1.10.0+ds-2) over (1.8.0-2build2) ... 251s Preparing to unpack .../24-libjson-glib-1.0-0_1.10.0+ds-2_ppc64el.deb ... 251s Unpacking libjson-glib-1.0-0:ppc64el (1.10.0+ds-2) over (1.8.0-2build2) ... 251s Preparing to unpack .../25-libnspr4_2%3a4.35-1.1ubuntu2_ppc64el.deb ... 251s Unpacking libnspr4:ppc64el (2:4.35-1.1ubuntu2) over (2:4.35-1.1ubuntu1) ... 251s Preparing to unpack .../26-udisks2_2.10.1-11ubuntu1_ppc64el.deb ... 251s Unpacking udisks2 (2.10.1-11ubuntu1) over (2.10.1-9ubuntu2) ... 251s Preparing to unpack .../27-libudisks2-0_2.10.1-11ubuntu1_ppc64el.deb ... 251s Unpacking libudisks2-0:ppc64el (2.10.1-11ubuntu1) over (2.10.1-9ubuntu2) ... 251s Preparing to unpack .../28-linux-firmware_20241108.git3bbb13a7-0ubuntu1_ppc64el.deb ... 251s Unpacking linux-firmware (20241108.git3bbb13a7-0ubuntu1) over (20240913.gita34e7a5f-0ubuntu2) ... 254s Selecting previously unselected package python3-bcrypt. 254s Preparing to unpack .../29-python3-bcrypt_4.2.0-1_ppc64el.deb ... 254s Unpacking python3-bcrypt (4.2.0-1) ... 254s Preparing to unpack .../30-python3-cryptography_43.0.0-1_ppc64el.deb ... 254s Unpacking python3-cryptography (43.0.0-1) over (42.0.5-2build1) ... 254s Preparing to unpack .../31-python3-idna_3.8-2_all.deb ... 254s Unpacking python3-idna (3.8-2) over (3.6-2.1) ... 254s Preparing to unpack .../32-python3-jsonpatch_1.32-4_all.deb ... 254s Unpacking python3-jsonpatch (1.32-4) over (1.32-3) ... 254s Preparing to unpack .../33-python3-wadllib_2.0.0-1_all.deb ... 254s Unpacking python3-wadllib (2.0.0-1) over (1.3.6-5) ... 254s Preparing to unpack .../34-python3-lazr.restfulclient_0.14.6-2_all.deb ... 254s Unpacking python3-lazr.restfulclient (0.14.6-2) over (0.14.6-1) ... 254s Preparing to unpack .../35-python3-urllib3_2.0.7-2ubuntu0.1_all.deb ... 254s Unpacking python3-urllib3 (2.0.7-2ubuntu0.1) over (2.0.7-2) ... 254s Selecting previously unselected package systemd-cryptsetup. 254s Preparing to unpack .../36-systemd-cryptsetup_256.5-2ubuntu4_ppc64el.deb ... 254s Unpacking systemd-cryptsetup (256.5-2ubuntu4) ... 254s Setting up liburcu8t64:ppc64el (0.14.1-1) ... 254s Setting up pci.ids (0.0~2024.10.24-1) ... 254s Setting up linux-firmware (20241108.git3bbb13a7-0ubuntu1) ... 254s Setting up openssh-client (1:9.7p1-7ubuntu5) ... 254s Setting up libmaxminddb0:ppc64el (1.11.0-1) ... 254s Setting up python3.12-gdbm (3.12.7-2) ... 254s Setting up python3-jsonpatch (1.32-4) ... 254s Setting up python3-bcrypt (4.2.0-1) ... 255s Setting up libpython3.12-minimal:ppc64el (3.12.7-2) ... 255s Setting up libnghttp2-14:ppc64el (1.64.0-1) ... 255s Setting up usbutils (1:018-1) ... 255s Setting up tzdata (2024b-1ubuntu2) ... 255s 255s Current default time zone: 'Etc/UTC' 255s Local time is now: Sat Nov 9 11:35:11 UTC 2024. 255s Universal Time is now: Sat Nov 9 11:35:11 UTC 2024. 255s Run 'dpkg-reconfigure tzdata' if you wish to change it. 255s 255s Setting up libnspr4:ppc64el (2:4.35-1.1ubuntu2) ... 255s Setting up bash-completion (1:2.14.0-2) ... 255s Setting up python3-cryptography (43.0.0-1) ... 255s Setting up python3-wadllib (2.0.0-1) ... 255s Setting up python3-gi (3.50.0-3) ... 255s Setting up libbytesize-common (2.11-1ubuntu1) ... 255s Setting up libblockdev-utils3:ppc64el (3.2.1-1) ... 255s Setting up libmnl0:ppc64el (1.0.5-3) ... 255s Setting up python3-idna (3.8-2) ... 255s Setting up libsystemd-shared:ppc64el (256.5-2ubuntu4) ... 255s Setting up python3-urllib3 (2.0.7-2ubuntu0.1) ... 256s Setting up libblockdev-nvme3:ppc64el (3.2.1-1) ... 256s Setting up libblockdev-fs3:ppc64el (3.2.1-1) ... 256s Setting up libjson-glib-1.0-common (1.10.0+ds-2) ... 256s Setting up libbytesize1:ppc64el (2.11-1ubuntu1) ... 256s Setting up python3-lazr.restfulclient (0.14.6-2) ... 256s Setting up libdrm-common (2.4.123-1) ... 256s Setting up libbpf1:ppc64el (1:1.4.6-1) ... 256s Setting up libudisks2-0:ppc64el (2.10.1-11ubuntu1) ... 256s Setting up python3.12-minimal (3.12.7-2) ... 257s Setting up openssh-sftp-server (1:9.7p1-7ubuntu5) ... 257s Setting up libpython3.12-stdlib:ppc64el (3.12.7-2) ... 257s Setting up libblockdev-mdraid3:ppc64el (3.2.1-1) ... 257s Setting up openssh-server (1:9.7p1-7ubuntu5) ... 258s Setting up libblockdev-crypto3:ppc64el (3.2.1-1) ... 258s Setting up libblockdev-swap3:ppc64el (3.2.1-1) ... 258s Setting up python3.12 (3.12.7-2) ... 259s Setting up libblockdev-loop3:ppc64el (3.2.1-1) ... 259s Setting up systemd (256.5-2ubuntu4) ... 259s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 259s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 259s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 260s Setting up libblockdev3:ppc64el (3.2.1-1) ... 260s Setting up libjson-glib-1.0-0:ppc64el (1.10.0+ds-2) ... 260s Setting up libblockdev-part3:ppc64el (3.2.1-1) ... 260s Setting up libpython3.12t64:ppc64el (3.12.7-2) ... 260s Setting up systemd-cryptsetup (256.5-2ubuntu4) ... 260s Setting up systemd-timesyncd (256.5-2ubuntu4) ... 260s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 261s Setting up udev (256.5-2ubuntu4) ... 261s Setting up libdrm2:ppc64el (2.4.123-1) ... 261s Setting up systemd-resolved (256.5-2ubuntu4) ... 262s Setting up udisks2 (2.10.1-11ubuntu1) ... 262s Setting up systemd-sysv (256.5-2ubuntu4) ... 262s Setting up libnss-systemd:ppc64el (256.5-2ubuntu4) ... 262s Setting up libpam-systemd:ppc64el (256.5-2ubuntu4) ... 262s Processing triggers for libc-bin (2.40-1ubuntu3) ... 262s Processing triggers for ufw (0.36.2-8) ... 263s Processing triggers for man-db (2.12.1-3) ... 264s Processing triggers for dbus (1.14.10-4ubuntu5) ... 264s Processing triggers for shared-mime-info (2.4-5) ... 264s Processing triggers for initramfs-tools (0.142ubuntu34) ... 264s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 264s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 271s Reading package lists... 272s Building dependency tree... 272s Reading state information... 272s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 272s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 272s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 272s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 272s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 273s Reading package lists... 273s Reading package lists... 273s Building dependency tree... 273s Reading state information... 274s Calculating upgrade... 274s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 274s Reading package lists... 274s Building dependency tree... 274s Reading state information... 274s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 274s autopkgtest [11:35:30]: rebooting testbed after setup commands that affected boot 278s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 307s Reading package lists... 307s Building dependency tree... 307s Reading state information... 307s Starting pkgProblemResolver with broken count: 0 307s Starting 2 pkgProblemResolver with broken count: 0 307s Done 307s The following additional packages will be installed: 307s net-tools stunnel4 307s Suggested packages: 307s logcheck-database 308s The following NEW packages will be installed: 308s autopkgtest-satdep net-tools stunnel4 308s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 308s Need to get 462 kB/463 kB of archives. 308s After this operation, 2075 kB of additional disk space will be used. 308s Get:1 /tmp/autopkgtest.193IuI/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [744 B] 308s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el stunnel4 ppc64el 3:5.72-3 [237 kB] 308s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el net-tools ppc64el 2.10-1.1ubuntu1 [225 kB] 308s Fetched 462 kB in 1s (909 kB/s) 308s Selecting previously unselected package stunnel4. 309s (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 ... 73789 files and directories currently installed.) 309s Preparing to unpack .../stunnel4_3%3a5.72-3_ppc64el.deb ... 309s Unpacking stunnel4 (3:5.72-3) ... 309s Selecting previously unselected package net-tools. 309s Preparing to unpack .../net-tools_2.10-1.1ubuntu1_ppc64el.deb ... 309s Unpacking net-tools (2.10-1.1ubuntu1) ... 309s Selecting previously unselected package autopkgtest-satdep. 309s Preparing to unpack .../2-autopkgtest-satdep.deb ... 309s Unpacking autopkgtest-satdep (0) ... 309s Setting up stunnel4 (3:5.72-3) ... 309s Creating group 'stunnel4' with GID 988. 309s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 309s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 309s Setting up net-tools (2.10-1.1ubuntu1) ... 309s Setting up autopkgtest-satdep (0) ... 309s Processing triggers for man-db (2.12.1-3) ... 312s (Reading database ... 73881 files and directories currently installed.) 312s Removing autopkgtest-satdep (0) ... 314s autopkgtest [11:36:10]: test upstream: debian/tests/upstream 314s autopkgtest [11:36:10]: test upstream: [----------------------- 315s keys & certificates successfully generated 315s ./maketestcert.sh finished 315s Stunnel was compiled and run with different OpenSSL versions 316s autopkgtest [11:36:12]: test upstream: -----------------------] 316s upstream PASS 316s autopkgtest [11:36:12]: test upstream: - - - - - - - - - - results - - - - - - - - - - 316s autopkgtest [11:36:12]: @@@@@@@@@@@@@@@@@@@@ summary 316s debian-python PASS 316s upstream PASS 321s virt: nova [W] Using flock in prodstack6-ppc64el 321s virt: Creating nova instance adt-plucky-ppc64el-stunnel4-20241109-113056-juju-7f2275-prod-proposed-migration-environment-2-80068727-98eb-4237-9c78-a59540e0ea37 from image adt/ubuntu-plucky-ppc64el-server-20241108.img (UUID 211c30ab-5e06-4166-a7df-df534accb012)... 321s virt: nova [W] Using flock in prodstack6-ppc64el 321s virt: Creating nova instance adt-plucky-ppc64el-stunnel4-20241109-113056-juju-7f2275-prod-proposed-migration-environment-2-80068727-98eb-4237-9c78-a59540e0ea37 from image adt/ubuntu-plucky-ppc64el-server-20241108.img (UUID 211c30ab-5e06-4166-a7df-df534accb012)...