0s autopkgtest [17:53:05]: starting date and time: 2025-03-15 17:53:05+0000 0s autopkgtest [17:53:05]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [17:53:05]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.67s5c6ar/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:glibc --apt-upgrade stunnel4 --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- lxd -r lxd-armhf-10.145.243.240 lxd-armhf-10.145.243.240:autopkgtest/ubuntu/plucky/armhf 21s autopkgtest [17:53:26]: testbed dpkg architecture: armhf 23s autopkgtest [17:53:28]: testbed apt version: 2.9.33 26s autopkgtest [17:53:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 28s autopkgtest [17:53:33]: testbed release detected to be: None 36s autopkgtest [17:53:41]: updating testbed package index (apt update) 38s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 38s Get:2 http://ftpmaster.internal/ubuntu plucky InRelease [257 kB] 38s Get:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease [126 kB] 39s Get:4 http://ftpmaster.internal/ubuntu plucky-security InRelease [126 kB] 39s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 39s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 39s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 39s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [114 kB] 40s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf c-n-f Metadata [1832 B] 40s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted armhf c-n-f Metadata [116 B] 40s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [312 kB] 40s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf c-n-f Metadata [11.1 kB] 40s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [3472 B] 40s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf c-n-f Metadata [240 B] 40s Get:15 http://ftpmaster.internal/ubuntu plucky/multiverse Sources [299 kB] 40s Get:16 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB] 63s Get:17 http://ftpmaster.internal/ubuntu plucky/main Sources [1394 kB] 65s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf Packages [1378 kB] 67s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf c-n-f Metadata [29.4 kB] 67s Get:20 http://ftpmaster.internal/ubuntu plucky/restricted armhf c-n-f Metadata [108 B] 67s Get:21 http://ftpmaster.internal/ubuntu plucky/universe armhf Packages [15.1 MB] 84s Get:22 http://ftpmaster.internal/ubuntu plucky/multiverse armhf Packages [172 kB] 86s Fetched 41.0 MB in 48s (853 kB/s) 87s Reading package lists... 93s autopkgtest [17:54:38]: upgrading testbed (apt dist-upgrade and autopurge) 94s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 95s Starting 2 pkgProblemResolver with broken count: 0 95s Done 96s Entering ResolveByKeep 96s 96s Calculating upgrade... 97s The following packages will be upgraded: 97s libc-bin libc6 locales pinentry-curses python3-jinja2 sos strace 97s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Need to get 8683 kB of archives. 97s After this operation, 23.6 kB of additional disk space will be used. 97s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc6 armhf 2.41-1ubuntu2 [2932 kB] 101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc-bin armhf 2.41-1ubuntu2 [545 kB] 101s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf locales all 2.41-1ubuntu2 [4246 kB] 106s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf strace armhf 6.13+ds-1ubuntu1 [445 kB] 106s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf pinentry-curses armhf 1.3.1-2ubuntu3 [40.6 kB] 106s Get:6 http://ftpmaster.internal/ubuntu plucky/main armhf python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 106s Get:7 http://ftpmaster.internal/ubuntu plucky/main armhf sos all 4.9.0-5 [365 kB] 107s Preconfiguring packages ... 107s Fetched 8683 kB in 10s (898 kB/s) 107s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64655 files and directories currently installed.) 107s Preparing to unpack .../libc6_2.41-1ubuntu2_armhf.deb ... 107s Unpacking libc6:armhf (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Setting up libc6:armhf (2.41-1ubuntu2) ... 108s (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 ... 64655 files and directories currently installed.) 108s Preparing to unpack .../libc-bin_2.41-1ubuntu2_armhf.deb ... 108s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Setting up libc-bin (2.41-1ubuntu2) ... 108s (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 ... 64655 files and directories currently installed.) 108s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 108s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Preparing to unpack .../strace_6.13+ds-1ubuntu1_armhf.deb ... 108s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 109s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_armhf.deb ... 109s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 109s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 109s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 109s Preparing to unpack .../archives/sos_4.9.0-5_all.deb ... 109s Unpacking sos (4.9.0-5) over (4.9.0-4) ... 109s Setting up sos (4.9.0-5) ... 110s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 110s Setting up locales (2.41-1ubuntu2) ... 110s Generating locales (this might take a while)... 112s en_US.UTF-8... done 112s Generation complete. 112s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 112s Setting up strace (6.13+ds-1ubuntu1) ... 112s Processing triggers for man-db (2.13.0-1) ... 114s Processing triggers for systemd (257.3-1ubuntu3) ... 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 117s Starting pkgProblemResolver with broken count: 0 117s Starting 2 pkgProblemResolver with broken count: 0 117s Done 117s Solving dependencies... 118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s autopkgtest [17:55:05]: rebooting testbed after setup commands that affected boot 167s autopkgtest [17:55:52]: testbed running kernel: Linux 6.8.0-52-generic #53~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Wed Jan 15 18:10:51 UTC 2 194s autopkgtest [17:56:19]: @@@@@@@@@@@@@@@@@@@@ apt-source stunnel4 205s Get:1 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (dsc) [2845 B] 205s Get:2 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (tar) [904 kB] 205s Get:3 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (asc) [833 B] 205s Get:4 http://ftpmaster.internal/ubuntu plucky/universe stunnel4 3:5.74-2 (diff) [54.8 kB] 206s gpgv: Signature made Tue Jan 28 14:01:27 2025 UTC 206s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 206s gpgv: issuer "roam@debian.org" 206s gpgv: Can't check signature: No public key 206s dpkg-source: warning: cannot verify inline signature for ./stunnel4_5.74-2.dsc: no acceptable signature found 206s autopkgtest [17:56:31]: testing package stunnel4 version 3:5.74-2 208s autopkgtest [17:56:33]: build not needed 211s autopkgtest [17:56:36]: test debian-python: preparing testbed 212s Reading package lists... 213s Building dependency tree... 213s Reading state information... 214s Starting pkgProblemResolver with broken count: 0 215s Starting 2 pkgProblemResolver with broken count: 0 215s Done 217s The following NEW packages will be installed: 217s python3-all stunnel4 217s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 217s Need to get 213 kB of archives. 217s After this operation, 591 kB of additional disk space will be used. 217s Get:1 http://ftpmaster.internal/ubuntu plucky/universe armhf stunnel4 armhf 3:5.74-2 [212 kB] 217s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf python3-all armhf 3.13.2-2 [886 B] 218s Fetched 213 kB in 1s (386 kB/s) 218s Selecting previously unselected package stunnel4. 218s (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 ... 64655 files and directories currently installed.) 218s Preparing to unpack .../stunnel4_3%3a5.74-2_armhf.deb ... 218s Unpacking stunnel4 (3:5.74-2) ... 218s Selecting previously unselected package python3-all. 218s Preparing to unpack .../python3-all_3.13.2-2_armhf.deb ... 218s Unpacking python3-all (3.13.2-2) ... 218s Setting up stunnel4 (3:5.74-2) ... 218s Creating group 'stunnel4' with GID 988. 218s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 218s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 219s Setting up python3-all (3.13.2-2) ... 219s Processing triggers for man-db (2.13.0-1) ... 227s autopkgtest [17:56:52]: 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 227s autopkgtest [17:56:52]: test debian-python: [----------------------- 229s 229s 229s ====== python3.13 229s 229s Using /tmp/struntime.5tqbccxd as a temporary directory 229s Trying to obtain the version of /usr/bin/stunnel4 229s Started `/usr/bin/stunnel4 -version` as process 931 229s Got stunnel version 5.74 229s [main] Starting to do things 229s [main] Awaiting the 'listener started' event 229s [find_listening_port] Looking for a port to listen on 229s [find_listening_port] Trying localhost port 6502 229s [listener] Telling the main thread about [127.0.0.1]:6502 229s [listener] Awaiting client connections... 229s [main] Apparently we are listening on [127.0.0.1]:6502 229s [main] Testing cleartext connections 229s [test_connect [127.0.0.1]:6502 0] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 1] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 2] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 3] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 4] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 5] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 6] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 7] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 8] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 9] Trying port 6502 encrypted False 229s [test_connect [127.0.0.1]:6502 2] Opening an unencrypted connection 229s [test_connect [127.0.0.1]:6502 5] Opening an unencrypted connection 229s [test_connect [127.0.0.1]:6502 6] Opening an unencrypted connection 229s [test_connect [127.0.0.1]:6502 8] Opening an unencrypted connection 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46228] New connection 229s [[127.0.0.1]:46228] Telling the main thread 229s [[127.0.0.1]:46228] Let them tell us something... 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46242] New connection 229s [[127.0.0.1]:46242] Telling the main thread 229s [[127.0.0.1]:46242] Let them tell us something... 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46252] New connection 229s [[127.0.0.1]:46252] Telling the main thread 229s [[127.0.0.1]:46252] Let them tell us something... 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46258] New connection 229s [[127.0.0.1]:46258] Telling the main thread 229s [[127.0.0.1]:46258] Let them tell us something... 229s [test_connect [127.0.0.1]:6502 2] Connected to the server: [127.0.0.1]:46228 229s [test_connect [127.0.0.1]:6502 5] Connected to the server: [127.0.0.1]:46242 229s [test_connect [127.0.0.1]:6502 6] Connected to the server: [127.0.0.1]:46252 229s [test_connect [127.0.0.1]:6502 8] Connected to the server: [127.0.0.1]:46258 229s [test_connect [127.0.0.1]:6502 0] Opening an unencrypted connection 229s [test_connect [127.0.0.1]:6502 7] Opening an unencrypted connection 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46262] New connection 229s [[127.0.0.1]:46262] Telling the main thread 229s [[127.0.0.1]:46262] Let them tell us something... 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46274] New connection 229s [[127.0.0.1]:46274] Telling the main thread 229s [[127.0.0.1]:46274] Let them tell us something... 229s [test_connect [127.0.0.1]:6502 0] Connected to the server: [127.0.0.1]:46262 229s [test_connect [127.0.0.1]:6502 7] Connected to the server: [127.0.0.1]:46274 229s [test_connect [127.0.0.1]:6502 9] Opening an unencrypted connection 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46286] New connection 229s [[127.0.0.1]:46286] Telling the main thread 229s [[127.0.0.1]:46286] Let them tell us something... 229s [test_connect [127.0.0.1]:6502 9] Connected to the server: [127.0.0.1]:46286 229s [test_connect [127.0.0.1]:6502 1] Opening an unencrypted connection 229s [test_connect [127.0.0.1]:6502 4] Opening an unencrypted connection 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46298] New connection 229s [[127.0.0.1]:46298] Telling the main thread 229s [[127.0.0.1]:46298] Let them tell us something... 229s [listener] A client connected from somewhere 229s [[127.0.0.1]:46314] New connection 229s [[127.0.0.1]:46314] Telling the main thread 229s [[127.0.0.1]:46314] Let them tell us something... 229s [test_connect [127.0.0.1]:6502 1] Connected to the server: [127.0.0.1]:46298 229s [test_connect [127.0.0.1]:6502 4] Connected to the server: [127.0.0.1]:46314 229s [test_connect [127.0.0.1]:6502 5] Sending something... 229s [test_connect [127.0.0.1]:6502 5] Waiting for the main thread to figure it out 229s [[127.0.0.1]:46242] Telling the main thread about b'Hello 5\n' 229s [[127.0.0.1]:46242] Writing something back... 229s [[127.0.0.1]:46242] Telling the main thread about b'There!\n' 229s [[127.0.0.1]:46242] Closing down the writer socket 229s [test_connect [127.0.0.1]:6502 5] The listener acknowledged [127.0.0.1]:46242 229s [test_connect [127.0.0.1]:6502 5] Waiting for the server to receive it... 229s [test_connect [127.0.0.1]:6502 5] Waiting for the server to send something 229s [test_connect [127.0.0.1]:6502 5] Trying to receive the actual data 229s [test_connect [127.0.0.1]:6502 5] Waiting for the server to close the connection 229s [[127.0.0.1]:46242] Telling the main thread done 229s [test_connect [127.0.0.1]:6502 5] Waiting for an EOF on the reader socket 229s [test_connect [127.0.0.1]:6502 5] Closing our writer socket, too 229s [test_connect [127.0.0.1]:6502 5] Letting the main thread know we're done 229s [test_connect [127.0.0.1]:6502 5] Done 229s [process_connections] Fetching task plain-5 229s [process_connections] Waiting for task plain-5 229s [process_connections] Done with task plain-5 230s [test_connect [127.0.0.1]:6502 6] Sending something... 230s [test_connect [127.0.0.1]:6502 6] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46252] Telling the main thread about b'Hello 6\n' 230s [[127.0.0.1]:46252] Writing something back... 230s [[127.0.0.1]:46252] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46252] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 6] The listener acknowledged [127.0.0.1]:46252 230s [test_connect [127.0.0.1]:6502 6] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 6] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 6] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 6] Waiting for the server to close the connection 230s [[127.0.0.1]:46252] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 6] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 6] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 6] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 6] Done 230s [process_connections] Fetching task plain-6 230s [process_connections] Waiting for task plain-6 230s [process_connections] Done with task plain-6 230s [test_connect [127.0.0.1]:6502 3] Opening an unencrypted connection 230s [listener] A client connected from somewhere 230s [[127.0.0.1]:46318] New connection 230s [[127.0.0.1]:46318] Telling the main thread 230s [[127.0.0.1]:46318] Let them tell us something... 230s [test_connect [127.0.0.1]:6502 3] Connected to the server: [127.0.0.1]:46318 230s [test_connect [127.0.0.1]:6502 8] Sending something... 230s [test_connect [127.0.0.1]:6502 8] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46258] Telling the main thread about b'Hello 8\n' 230s [[127.0.0.1]:46258] Writing something back... 230s [[127.0.0.1]:46258] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46258] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 8] The listener acknowledged [127.0.0.1]:46258 230s [test_connect [127.0.0.1]:6502 8] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 8] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 8] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 8] Waiting for the server to close the connection 230s [[127.0.0.1]:46258] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 8] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 8] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 8] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 8] Done 230s [process_connections] Fetching task plain-8 230s [process_connections] Waiting for task plain-8 230s [process_connections] Done with task plain-8 230s [test_connect [127.0.0.1]:6502 9] Sending something... 230s [test_connect [127.0.0.1]:6502 9] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46286] Telling the main thread about b'Hello 9\n' 230s [[127.0.0.1]:46286] Writing something back... 230s [[127.0.0.1]:46286] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46286] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 2] Sending something... 230s [test_connect [127.0.0.1]:6502 2] Waiting for the main thread to figure it out 230s [test_connect [127.0.0.1]:6502 9] The listener acknowledged [127.0.0.1]:46286 230s [test_connect [127.0.0.1]:6502 9] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 9] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 9] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 9] Waiting for the server to close the connection 230s [[127.0.0.1]:46286] Telling the main thread done 230s [[127.0.0.1]:46228] Telling the main thread about b'Hello 2\n' 230s [[127.0.0.1]:46228] Writing something back... 230s [[127.0.0.1]:46228] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46228] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 9] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 9] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 2] The listener acknowledged [127.0.0.1]:46228 230s [test_connect [127.0.0.1]:6502 2] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 2] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 2] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 2] Waiting for the server to close the connection 230s [[127.0.0.1]:46228] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 9] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 9] Done 230s [test_connect [127.0.0.1]:6502 2] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 2] Closing our writer socket, too 230s [process_connections] Fetching task plain-9 230s [process_connections] Waiting for task plain-9 230s [process_connections] Done with task plain-9 230s [test_connect [127.0.0.1]:6502 2] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 2] Done 230s [process_connections] Fetching task plain-2 230s [process_connections] Waiting for task plain-2 230s [process_connections] Done with task plain-2 230s [test_connect [127.0.0.1]:6502 0] Sending something... 230s [test_connect [127.0.0.1]:6502 0] Waiting for the main thread to figure it out 230s [test_connect [127.0.0.1]:6502 7] Sending something... 230s [test_connect [127.0.0.1]:6502 7] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46262] Telling the main thread about b'Hello 0\n' 230s [[127.0.0.1]:46262] Writing something back... 230s [[127.0.0.1]:46262] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46262] Closing down the writer socket 230s [[127.0.0.1]:46274] Telling the main thread about b'Hello 7\n' 230s [[127.0.0.1]:46274] Writing something back... 230s [[127.0.0.1]:46274] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46274] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 0] The listener acknowledged [127.0.0.1]:46262 230s [test_connect [127.0.0.1]:6502 0] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 0] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 0] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 0] Waiting for the server to close the connection 230s [test_connect [127.0.0.1]:6502 7] The listener acknowledged [127.0.0.1]:46274 230s [test_connect [127.0.0.1]:6502 7] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 7] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 7] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 7] Waiting for the server to close the connection 230s [[127.0.0.1]:46262] Telling the main thread done 230s [[127.0.0.1]:46274] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 1] Sending something... 230s [test_connect [127.0.0.1]:6502 1] Waiting for the main thread to figure it out 230s [test_connect [127.0.0.1]:6502 0] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 0] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 7] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 7] Closing our writer socket, too 230s [[127.0.0.1]:46298] Telling the main thread about b'Hello 1\n' 230s [[127.0.0.1]:46298] Writing something back... 230s [[127.0.0.1]:46298] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46298] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 0] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 0] Done 230s [test_connect [127.0.0.1]:6502 7] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 7] Done 230s [process_connections] Fetching task plain-0 230s [process_connections] Waiting for task plain-0 230s [process_connections] Done with task plain-0 230s [process_connections] Fetching task plain-7 230s [process_connections] Waiting for task plain-7 230s [process_connections] Done with task plain-7 230s [test_connect [127.0.0.1]:6502 1] The listener acknowledged [127.0.0.1]:46298 230s [test_connect [127.0.0.1]:6502 1] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 1] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 1] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 1] Waiting for the server to close the connection 230s [[127.0.0.1]:46298] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 1] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 1] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 1] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 1] Done 230s [process_connections] Fetching task plain-1 230s [process_connections] Waiting for task plain-1 230s [process_connections] Done with task plain-1 230s [test_connect [127.0.0.1]:6502 3] Sending something... 230s [test_connect [127.0.0.1]:6502 3] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46318] Telling the main thread about b'Hello 3\n' 230s [[127.0.0.1]:46318] Writing something back... 230s [[127.0.0.1]:46318] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46318] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 3] The listener acknowledged [127.0.0.1]:46318 230s [test_connect [127.0.0.1]:6502 3] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 3] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 3] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 3] Waiting for the server to close the connection 230s [[127.0.0.1]:46318] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 3] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 3] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 3] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 3] Done 230s [process_connections] Fetching task plain-3 230s [process_connections] Waiting for task plain-3 230s [process_connections] Done with task plain-3 230s [test_connect [127.0.0.1]:6502 4] Sending something... 230s [test_connect [127.0.0.1]:6502 4] Waiting for the main thread to figure it out 230s [[127.0.0.1]:46314] Telling the main thread about b'Hello 4\n' 230s [[127.0.0.1]:46314] Writing something back... 230s [[127.0.0.1]:46314] Telling the main thread about b'There!\n' 230s [[127.0.0.1]:46314] Closing down the writer socket 230s [test_connect [127.0.0.1]:6502 4] The listener acknowledged [127.0.0.1]:46314 230s [test_connect [127.0.0.1]:6502 4] Waiting for the server to receive it... 230s [test_connect [127.0.0.1]:6502 4] Waiting for the server to send something 230s [test_connect [127.0.0.1]:6502 4] Trying to receive the actual data 230s [test_connect [127.0.0.1]:6502 4] Waiting for the server to close the connection 230s [[127.0.0.1]:46314] Telling the main thread done 230s [test_connect [127.0.0.1]:6502 4] Waiting for an EOF on the reader socket 230s [test_connect [127.0.0.1]:6502 4] Closing our writer socket, too 230s [test_connect [127.0.0.1]:6502 4] Letting the main thread know we're done 230s [test_connect [127.0.0.1]:6502 4] Done 230s [process_connections] Fetching task plain-4 230s [process_connections] Waiting for task plain-4 230s [process_connections] Done with task plain-4 230s [main] Picking a listen address:port for stunnel 230s [find_listening_port] Looking for a port to listen on 230s [find_listening_port] Trying 127.0.0.1 port 6503 230s [find_listening_port] got 6503, shutting down the listener 230s [main] Will put stunnel at 127.0.0.1:6503 230s [main] Preparing the stunnel config file 230s [main] Using config file /tmp/struntime.5tqbccxd/stunnel.conf 230s [main] 230s [main] pid = /tmp/struntime.5tqbccxd/stunnel.pid 230s [main] foreground = yes 230s [main] 230s [main] cert = /tmp/autopkgtest.wdPmb8/build.vOD/src/debian/tests/certs/certificate.pem 230s [main] key = /tmp/struntime.5tqbccxd/key.pem 230s [main] 230s [main] [test] 230s [main] accept = 127.0.0.1:6503 230s [main] connect = 127.0.0.1:6502 230s [main] End of the config file 230s [main] Launching the stunnel server 230s [main] - got pid 935 230s [main] Launching the stunnel output pipe thread 230s [main] Waiting for stunnel to start up 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: stunnel 5.74 on arm-unknown-linux-gnueabihf platform' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: Compiled with OpenSSL 3.4.0 22 Oct 2024' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: Running with OpenSSL 3.4.1 11 Feb 2025' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD TLS:ENGINE,OCSP,PSK,SNI Auth:LIBWRAP' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: Reading configuration from file /tmp/struntime.5tqbccxd/stunnel.conf' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: UTF-8 byte order mark not detected' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: FIPS mode disabled' 230s [stunnel_output] Read '2025.03.15 17:56:55 LOG5[ui]: Configuration successful' 230s [stunnel_output] The main thread ought to know 230s [main] Testing the encrypted connections 230s [test_connect [127.0.0.1]:6503 0] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 1] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 2] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 3] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 4] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 5] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 6] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 7] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 8] Trying port 6503 encrypted True 230s [test_connect [127.0.0.1]:6503 9] Trying port 6503 encrypted True 231s [test_connect [127.0.0.1]:6503 0] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 0] - cert required 231s [test_connect [127.0.0.1]:6503 0] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 0] Opening an SSL connection 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[0]: Service [test] accepted connection from 127.0.0.1:38594' 231s [test_connect [127.0.0.1]:6503 0] Connected to the server: [127.0.0.1]:38594 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[0]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[0]: Service [test] connected remote server from 127.0.0.1:46328' 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46328] New connection 231s [[127.0.0.1]:46328] Telling the main thread 231s [[127.0.0.1]:46328] Let them tell us something... 231s [test_connect [127.0.0.1]:6503 3] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 3] - cert required 231s [test_connect [127.0.0.1]:6503 3] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 3] Opening an SSL connection 231s [test_connect [127.0.0.1]:6503 4] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 4] - cert required 231s [test_connect [127.0.0.1]:6503 4] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 4] Opening an SSL connection 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[1]: Service [test] accepted connection from 127.0.0.1:38602' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[2]: Service [test] accepted connection from 127.0.0.1:38606' 231s [test_connect [127.0.0.1]:6503 4] Connected to the server: [127.0.0.1]:38606 231s [test_connect [127.0.0.1]:6503 3] Connected to the server: [127.0.0.1]:38602 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[2]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[2]: Service [test] connected remote server from 127.0.0.1:46338' 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46338] New connection 231s [[127.0.0.1]:46338] Telling the main thread 231s [[127.0.0.1]:46338] Let them tell us something... 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[1]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[1]: Service [test] connected remote server from 127.0.0.1:46342' 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46342] New connection 231s [[127.0.0.1]:46342] Telling the main thread 231s [[127.0.0.1]:46342] Let them tell us something... 231s [test_connect [127.0.0.1]:6503 6] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 6] - cert required 231s [test_connect [127.0.0.1]:6503 6] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 6] Opening an SSL connection 231s [test_connect [127.0.0.1]:6503 7] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 7] - cert required 231s [test_connect [127.0.0.1]:6503 7] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 7] Opening an SSL connection 231s [test_connect [127.0.0.1]:6503 9] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 9] - cert required 231s [test_connect [127.0.0.1]:6503 9] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 9] Opening an SSL connection 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[3]: Service [test] accepted connection from 127.0.0.1:38612' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[4]: Service [test] accepted connection from 127.0.0.1:38626' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[5]: Service [test] accepted connection from 127.0.0.1:38632' 231s [test_connect [127.0.0.1]:6503 6] Connected to the server: [127.0.0.1]:38612 231s [test_connect [127.0.0.1]:6503 7] Connected to the server: [127.0.0.1]:38626 231s [test_connect [127.0.0.1]:6503 9] Connected to the server: [127.0.0.1]:38632 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[3]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[3]: Service [test] connected remote server from 127.0.0.1:46358' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[4]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[4]: Service [test] connected remote server from 127.0.0.1:46374' 231s [test_connect [127.0.0.1]:6503 0] Sending something... 231s [test_connect [127.0.0.1]:6503 0] Waiting for the main thread to figure it out 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46358] New connection 231s [[127.0.0.1]:46358] Telling the main thread 231s [[127.0.0.1]:46358] Let them tell us something... 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46374] New connection 231s [[127.0.0.1]:46374] Telling the main thread 231s [[127.0.0.1]:46374] Let them tell us something... 231s [[127.0.0.1]:46328] Telling the main thread about b'Hello 0\n' 231s [[127.0.0.1]:46328] Writing something back... 231s [[127.0.0.1]:46328] Telling the main thread about b'There!\n' 231s [[127.0.0.1]:46328] Closing down the writer socket 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[5]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[5]: Service [test] connected remote server from 127.0.0.1:46378' 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46378] New connection 231s [[127.0.0.1]:46378] Telling the main thread 231s [[127.0.0.1]:46378] Let them tell us something... 231s [test_connect [127.0.0.1]:6503 0] The listener acknowledged [127.0.0.1]:46328 231s [test_connect [127.0.0.1]:6503 0] Waiting for the server to receive it... 231s [test_connect [127.0.0.1]:6503 0] Waiting for the server to send something 231s [test_connect [127.0.0.1]:6503 0] Trying to receive the actual data 231s [test_connect [127.0.0.1]:6503 0] Waiting for the server to close the connection 231s [[127.0.0.1]:46328] Telling the main thread done 231s [test_connect [127.0.0.1]:6503 0] Waiting for an EOF on the reader socket 231s [test_connect [127.0.0.1]:6503 0] Closing our writer socket, too 231s [test_connect [127.0.0.1]:6503 0] Letting the main thread know we're done 231s [test_connect [127.0.0.1]:6503 0] Done 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[0]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 231s [process_connections] Fetching task encr-0 231s [process_connections] Waiting for task encr-0 231s [process_connections] Done with task encr-0 231s [test_connect [127.0.0.1]:6503 2] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 2] - cert required 231s [test_connect [127.0.0.1]:6503 2] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 2] Opening an SSL connection 231s [test_connect [127.0.0.1]:6503 5] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 5] - cert required 231s [test_connect [127.0.0.1]:6503 5] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 5] Opening an SSL connection 231s [test_connect [127.0.0.1]:6503 8] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 8] - cert required 231s [test_connect [127.0.0.1]:6503 8] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 8] Opening an SSL connection 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[6]: Service [test] accepted connection from 127.0.0.1:38634' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[7]: Service [test] accepted connection from 127.0.0.1:38648' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[8]: Service [test] accepted connection from 127.0.0.1:38656' 231s [test_connect [127.0.0.1]:6503 2] Connected to the server: [127.0.0.1]:38634 231s [test_connect [127.0.0.1]:6503 5] Connected to the server: [127.0.0.1]:38648 231s [test_connect [127.0.0.1]:6503 8] Connected to the server: [127.0.0.1]:38656 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[6]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[6]: Service [test] connected remote server from 127.0.0.1:46388' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[7]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[7]: Service [test] connected remote server from 127.0.0.1:46390' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[8]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[8]: Service [test] connected remote server from 127.0.0.1:46398' 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46388] New connection 231s [[127.0.0.1]:46388] Telling the main thread 231s [[127.0.0.1]:46388] Let them tell us something... 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46390] New connection 231s [[127.0.0.1]:46390] Telling the main thread 231s [[127.0.0.1]:46390] Let them tell us something... 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46398] New connection 231s [[127.0.0.1]:46398] Telling the main thread 231s [[127.0.0.1]:46398] Let them tell us something... 231s [test_connect [127.0.0.1]:6503 1] Creating an SSL context 231s [test_connect [127.0.0.1]:6503 1] - cert required 231s [test_connect [127.0.0.1]:6503 1] - load_verify_locations() 231s [test_connect [127.0.0.1]:6503 1] Opening an SSL connection 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[9]: Service [test] accepted connection from 127.0.0.1:38658' 231s [test_connect [127.0.0.1]:6503 1] Connected to the server: [127.0.0.1]:38658 231s [listener] A client connected from somewhere 231s [[127.0.0.1]:46400] New connection 231s [[127.0.0.1]:46400] Telling the main thread 231s [[127.0.0.1]:46400] Let them tell us something... 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[9]: s_connect: connected 127.0.0.1:6502' 231s [stunnel_output] Read '2025.03.15 17:56:56 LOG5[9]: Service [test] connected remote server from 127.0.0.1:46400' 232s [test_connect [127.0.0.1]:6503 4] Sending something... 232s [test_connect [127.0.0.1]:6503 4] Waiting for the main thread to figure it out 232s [test_connect [127.0.0.1]:6503 5] Sending something... 232s [test_connect [127.0.0.1]:6503 5] Waiting for the main thread to figure it out 232s [[127.0.0.1]:46338] Telling the main thread about b'Hello 4\n' 232s [[127.0.0.1]:46338] Writing something back... 232s [[127.0.0.1]:46338] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46338] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 4] The listener acknowledged [127.0.0.1]:46338 232s [test_connect [127.0.0.1]:6503 4] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 4] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 4] Trying to receive the actual data 232s [test_connect [127.0.0.1]:6503 4] Waiting for the server to close the connection 232s [[127.0.0.1]:46338] Telling the main thread done 232s [[127.0.0.1]:46390] Telling the main thread about b'Hello 5\n' 232s [[127.0.0.1]:46390] Writing something back... 232s [[127.0.0.1]:46390] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46390] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 7] Sending something... 232s [test_connect [127.0.0.1]:6503 7] Waiting for the main thread to figure it out 232s [test_connect [127.0.0.1]:6503 4] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 4] Closing our writer socket, too 232s [test_connect [127.0.0.1]:6503 5] The listener acknowledged [127.0.0.1]:46390 232s [test_connect [127.0.0.1]:6503 5] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 5] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 5] Trying to receive the actual data 232s [test_connect [127.0.0.1]:6503 5] Waiting for the server to close the connection 232s [[127.0.0.1]:46390] Telling the main thread done 232s [test_connect [127.0.0.1]:6503 4] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 4] Done 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[2]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 232s [[127.0.0.1]:46374] Telling the main thread about b'Hello 7\n' 232s [[127.0.0.1]:46374] Writing something back... 232s [[127.0.0.1]:46374] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46374] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 5] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 5] Closing our writer socket, too 232s [process_connections] Fetching task encr-4 232s [process_connections] Waiting for task encr-4 232s [process_connections] Done with task encr-4 232s [test_connect [127.0.0.1]:6503 7] The listener acknowledged [127.0.0.1]:46374 232s [test_connect [127.0.0.1]:6503 7] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 7] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 7] Trying to receive the actual data 232s [test_connect [127.0.0.1]:6503 5] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 5] Done 232s [[127.0.0.1]:46374] Telling the main thread done 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[7]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 232s [process_connections] Fetching task encr-5 232s [process_connections] Waiting for task encr-5 232s [process_connections] Done with task encr-5 232s [test_connect [127.0.0.1]:6503 7] Waiting for the server to close the connection 232s [test_connect [127.0.0.1]:6503 7] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 7] Closing our writer socket, too 232s [test_connect [127.0.0.1]:6503 7] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 7] Done 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[4]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 232s [process_connections] Fetching task encr-7 232s [process_connections] Waiting for task encr-7 232s [process_connections] Done with task encr-7 232s [test_connect [127.0.0.1]:6503 3] Sending something... 232s [test_connect [127.0.0.1]:6503 3] Waiting for the main thread to figure it out 232s [[127.0.0.1]:46342] Telling the main thread about b'Hello 3\n' 232s [[127.0.0.1]:46342] Writing something back... 232s [[127.0.0.1]:46342] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46342] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 3] The listener acknowledged [127.0.0.1]:46342 232s [test_connect [127.0.0.1]:6503 3] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 3] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 3] Trying to receive the actual data 232s [[127.0.0.1]:46342] Telling the main thread done 232s [test_connect [127.0.0.1]:6503 3] Waiting for the server to close the connection 232s [test_connect [127.0.0.1]:6503 3] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 3] Closing our writer socket, too 232s [test_connect [127.0.0.1]:6503 3] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 3] Done 232s [process_connections] Fetching task encr-3 232s [process_connections] Waiting for task encr-3 232s [process_connections] Done with task encr-3 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[1]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 232s [test_connect [127.0.0.1]:6503 2] Sending something... 232s [test_connect [127.0.0.1]:6503 2] Waiting for the main thread to figure it out 232s [[127.0.0.1]:46388] Telling the main thread about b'Hello 2\n' 232s [[127.0.0.1]:46388] Writing something back... 232s [[127.0.0.1]:46388] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46388] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 2] The listener acknowledged [127.0.0.1]:46388 232s [test_connect [127.0.0.1]:6503 2] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 2] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 2] Trying to receive the actual data 232s [test_connect [127.0.0.1]:6503 2] Waiting for the server to close the connection 232s [[127.0.0.1]:46388] Telling the main thread done 232s [test_connect [127.0.0.1]:6503 2] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 2] Closing our writer socket, too 232s [test_connect [127.0.0.1]:6503 2] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 2] Done 232s [process_connections] Fetching task encr-2 232s [process_connections] Waiting for task encr-2 232s [process_connections] Done with task encr-2 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[6]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 232s [test_connect [127.0.0.1]:6503 9] Sending something... 232s [test_connect [127.0.0.1]:6503 9] Waiting for the main thread to figure it out 232s [[127.0.0.1]:46378] Telling the main thread about b'Hello 9\n' 232s [[127.0.0.1]:46378] Writing something back... 232s [[127.0.0.1]:46378] Telling the main thread about b'There!\n' 232s [[127.0.0.1]:46378] Closing down the writer socket 232s [test_connect [127.0.0.1]:6503 9] The listener acknowledged [127.0.0.1]:46378 232s [test_connect [127.0.0.1]:6503 9] Waiting for the server to receive it... 232s [test_connect [127.0.0.1]:6503 9] Waiting for the server to send something 232s [test_connect [127.0.0.1]:6503 9] Trying to receive the actual data 232s [[127.0.0.1]:46378] Telling the main thread done 232s [test_connect [127.0.0.1]:6503 9] Waiting for the server to close the connection 232s [test_connect [127.0.0.1]:6503 9] Waiting for an EOF on the reader socket 232s [test_connect [127.0.0.1]:6503 9] Closing our writer socket, too 232s [test_connect [127.0.0.1]:6503 9] Letting the main thread know we're done 232s [test_connect [127.0.0.1]:6503 9] Done 232s [process_connections] Fetching task encr-9 232s [process_connections] Waiting for task encr-9 232s [process_connections] Done with task encr-9 232s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[5]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 233s [test_connect [127.0.0.1]:6503 1] Sending something... 233s [test_connect [127.0.0.1]:6503 1] Waiting for the main thread to figure it out 233s autopkgtest [17:56:58]: test debian-python: -----------------------] 233s [[127.0.0.1]:46400] Telling the main thread about b'Hello 1\n' 233s [[127.0.0.1]:46400] Writing something back... 233s [[127.0.0.1]:46400] Telling the main thread about b'There!\n' 233s [[127.0.0.1]:46400] Closing down the writer socket 233s [test_connect [127.0.0.1]:6503 1] The listener acknowledged [127.0.0.1]:46400 233s [test_connect [127.0.0.1]:6503 1] Waiting for the server to receive it... 233s [test_connect [127.0.0.1]:6503 1] Waiting for the server to send something 233s [test_connect [127.0.0.1]:6503 1] Trying to receive the actual data 233s [[127.0.0.1]:46400] Telling the main thread done 233s [test_connect [127.0.0.1]:6503 1] Waiting for the server to close the connection 233s [test_connect [127.0.0.1]:6503 1] Waiting for an EOF on the reader socket 233s [test_connect [127.0.0.1]:6503 1] Closing our writer socket, too 233s [test_connect [127.0.0.1]:6503 1] Letting the main thread know we're done 233s [test_connect [127.0.0.1]:6503 1] Done 233s [process_connections] Fetching task encr-1 233s [process_connections] Waiting for task encr-1 233s [process_connections] Done with task encr-1 233s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[9]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 233s [test_connect [127.0.0.1]:6503 6] Sending something... 233s [test_connect [127.0.0.1]:6503 6] Waiting for the main thread to figure it out 233s [[127.0.0.1]:46358] Telling the main thread about b'Hello 6\n' 233s [[127.0.0.1]:46358] Writing something back... 233s [[127.0.0.1]:46358] Telling the main thread about b'There!\n' 233s [[127.0.0.1]:46358] Closing down the writer socket 233s [test_connect [127.0.0.1]:6503 6] The listener acknowledged [127.0.0.1]:46358 233s [test_connect [127.0.0.1]:6503 6] Waiting for the server to receive it... 233s [test_connect [127.0.0.1]:6503 6] Waiting for the server to send something 233s [test_connect [127.0.0.1]:6503 6] Trying to receive the actual data 233s [test_connect [127.0.0.1]:6503 6] Waiting for the server to close the connection 233s [[127.0.0.1]:46358] Telling the main thread done 233s [test_connect [127.0.0.1]:6503 6] Waiting for an EOF on the reader socket 233s [test_connect [127.0.0.1]:6503 6] Closing our writer socket, too 233s [test_connect [127.0.0.1]:6503 6] Letting the main thread know we're done 233s [test_connect [127.0.0.1]:6503 6] Done 233s [process_connections] Fetching task encr-6 233s [process_connections] Waiting for task encr-6 233s [process_connections] Done with task encr-6 233s [stunnel_output] Read '2025.03.15 17:56:57 LOG5[3]: Connection closed: 7 byte(s) sent to TLS, 8 byte(s) sent to socket' 233s [test_connect [127.0.0.1]:6503 8] Sending something... 233s [test_connect [127.0.0.1]:6503 8] Waiting for the main thread to figure it out 233s [[127.0.0.1]:46398] Telling the main thread about b'Hello 8\n' 233s [[127.0.0.1]:46398] Writing something back... 233s [[127.0.0.1]:46398] Telling the main thread about b'There!\n' 233s [[127.0.0.1]:46398] Closing down the writer socket 233s [test_connect [127.0.0.1]:6503 8] The listener acknowledged [127.0.0.1]:46398 233s [test_connect [127.0.0.1]:6503 8] Waiting for the server to receive it... 233s [test_connect [127.0.0.1]:6503 8] Waiting for the server to send something 233s [test_connect [127.0.0.1]:6503 8] Trying to receive the actual data 233s [test_connect [127.0.0.1]:6503 8] Waiting for the server to close the connection 233s [[127.0.0.1]:46398] Telling the main thread done 233s [test_connect [127.0.0.1]:6503 8] Waiting for an EOF on the reader socket 233s [test_connect [127.0.0.1]:6503 8] Closing our writer socket, too 233s [test_connect [127.0.0.1]:6503 8] Letting the main thread know we're done 233s [test_connect [127.0.0.1]:6503 8] Done 233s [process_connections] Fetching task encr-8 233s [process_connections] Waiting for task encr-8 233s [process_connections] Done with task encr-8 233s [main] Everything seems to be all right! 233s About to cancel 2 remaining task(s) 233s - listen 233s - output 233s Waiting for the tasks to hopefully finish 233s About to kill and wait for 1 child process(es) 233s - pid 935 233s Waiting for the processes to exit... 233s Got processes' exit status: [-9] 237s debian-python PASS 237s autopkgtest [17:57:02]: test debian-python: - - - - - - - - - - results - - - - - - - - - - 241s autopkgtest [17:57:06]: test upstream: preparing testbed 265s autopkgtest [17:57:28]: testbed dpkg architecture: armhf 265s autopkgtest [17:57:30]: testbed apt version: 2.9.33 269s autopkgtest [17:57:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 271s autopkgtest [17:57:36]: testbed release detected to be: plucky 278s autopkgtest [17:57:43]: updating testbed package index (apt update) 280s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 280s Get:2 http://ftpmaster.internal/ubuntu plucky InRelease [257 kB] 281s Get:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease [126 kB] 281s Get:4 http://ftpmaster.internal/ubuntu plucky-security InRelease [126 kB] 281s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 281s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 281s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 282s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [114 kB] 282s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf c-n-f Metadata [1832 B] 282s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted armhf c-n-f Metadata [116 B] 282s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [312 kB] 282s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf c-n-f Metadata [11.1 kB] 282s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [3472 B] 282s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf c-n-f Metadata [240 B] 282s Get:15 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB] 301s Get:16 http://ftpmaster.internal/ubuntu plucky/multiverse Sources [299 kB] 301s Get:17 http://ftpmaster.internal/ubuntu plucky/main Sources [1394 kB] 302s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf Packages [1378 kB] 303s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf c-n-f Metadata [29.4 kB] 303s Get:20 http://ftpmaster.internal/ubuntu plucky/restricted armhf c-n-f Metadata [108 B] 303s Get:21 http://ftpmaster.internal/ubuntu plucky/universe armhf Packages [15.1 MB] 318s Get:22 http://ftpmaster.internal/ubuntu plucky/multiverse armhf Packages [172 kB] 319s Fetched 41.0 MB in 39s (1051 kB/s) 320s Reading package lists... 326s autopkgtest [17:58:31]: upgrading testbed (apt dist-upgrade and autopurge) 328s Reading package lists... 328s Building dependency tree... 328s Reading state information... 329s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 329s Starting 2 pkgProblemResolver with broken count: 0 329s Done 330s Entering ResolveByKeep 330s 330s Calculating upgrade... 331s The following packages will be upgraded: 331s libc-bin libc6 locales pinentry-curses python3-jinja2 sos strace 331s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 331s Need to get 8683 kB of archives. 331s After this operation, 23.6 kB of additional disk space will be used. 331s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc6 armhf 2.41-1ubuntu2 [2932 kB] 333s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc-bin armhf 2.41-1ubuntu2 [545 kB] 334s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf locales all 2.41-1ubuntu2 [4246 kB] 337s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf strace armhf 6.13+ds-1ubuntu1 [445 kB] 338s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf pinentry-curses armhf 1.3.1-2ubuntu3 [40.6 kB] 338s Get:6 http://ftpmaster.internal/ubuntu plucky/main armhf python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 338s Get:7 http://ftpmaster.internal/ubuntu plucky/main armhf sos all 4.9.0-5 [365 kB] 339s Preconfiguring packages ... 339s Fetched 8683 kB in 8s (1113 kB/s) 339s (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 ... 64655 files and directories currently installed.) 339s Preparing to unpack .../libc6_2.41-1ubuntu2_armhf.deb ... 339s Unpacking libc6:armhf (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 339s Setting up libc6:armhf (2.41-1ubuntu2) ... 340s (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 ... 64655 files and directories currently installed.) 340s Preparing to unpack .../libc-bin_2.41-1ubuntu2_armhf.deb ... 340s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 340s Setting up libc-bin (2.41-1ubuntu2) ... 340s (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 ... 64655 files and directories currently installed.) 340s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 340s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 340s Preparing to unpack .../strace_6.13+ds-1ubuntu1_armhf.deb ... 340s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 340s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_armhf.deb ... 340s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 340s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 340s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 340s Preparing to unpack .../archives/sos_4.9.0-5_all.deb ... 341s Unpacking sos (4.9.0-5) over (4.9.0-4) ... 341s Setting up sos (4.9.0-5) ... 341s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 341s Setting up locales (2.41-1ubuntu2) ... 342s Generating locales (this might take a while)... 344s en_US.UTF-8... done 344s Generation complete. 344s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 344s Setting up strace (6.13+ds-1ubuntu1) ... 344s Processing triggers for man-db (2.13.0-1) ... 346s Processing triggers for systemd (257.3-1ubuntu3) ... 348s Reading package lists... 348s Building dependency tree... 348s Reading state information... 349s Starting pkgProblemResolver with broken count: 0 349s Starting 2 pkgProblemResolver with broken count: 0 349s Done 349s Solving dependencies... 350s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 352s autopkgtest [17:58:57]: rebooting testbed after setup commands that affected boot 421s Reading package lists... 422s Building dependency tree... 422s Reading state information... 422s Starting pkgProblemResolver with broken count: 0 422s Starting 2 pkgProblemResolver with broken count: 0 422s Done 423s The following NEW packages will be installed: 423s net-tools stunnel4 423s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 423s Need to get 404 kB of archives. 423s After this operation, 1203 kB of additional disk space will be used. 423s Get:1 http://ftpmaster.internal/ubuntu plucky/universe armhf stunnel4 armhf 3:5.74-2 [212 kB] 424s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf net-tools armhf 2.10-1.1ubuntu1 [192 kB] 424s Fetched 404 kB in 1s (499 kB/s) 424s Selecting previously unselected package stunnel4. 424s (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 ... 64655 files and directories currently installed.) 424s Preparing to unpack .../stunnel4_3%3a5.74-2_armhf.deb ... 424s Unpacking stunnel4 (3:5.74-2) ... 424s Selecting previously unselected package net-tools. 424s Preparing to unpack .../net-tools_2.10-1.1ubuntu1_armhf.deb ... 424s Unpacking net-tools (2.10-1.1ubuntu1) ... 424s Setting up stunnel4 (3:5.74-2) ... 424s Creating group 'stunnel4' with GID 988. 424s Creating user 'stunnel4' (stunnel service system account) with UID 988 and GID 988. 425s Created symlink '/etc/systemd/system/multi-user.target.wants/stunnel.target' → '/usr/lib/systemd/system/stunnel.target'. 425s Setting up net-tools (2.10-1.1ubuntu1) ... 425s Processing triggers for man-db (2.13.0-1) ... 447s autopkgtest [18:00:32]: test upstream: debian/tests/upstream 447s autopkgtest [18:00:32]: test upstream: [----------------------- 452s keys & certificates successfully generated 452s ./maketestcert.sh finished 452s Stunnel was compiled and run with different OpenSSL versions 452s autopkgtest [18:00:37]: test upstream: -----------------------] 457s upstream PASS 457s autopkgtest [18:00:42]: test upstream: - - - - - - - - - - results - - - - - - - - - - 461s autopkgtest [18:00:46]: @@@@@@@@@@@@@@@@@@@@ summary 461s debian-python PASS 461s upstream PASS