0s autopkgtest [08:21:58]: starting date and time: 2026-02-08 08:21:58+0000 0s autopkgtest [08:21:58]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:21:58]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dttx9kkt/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python-snitun --apt-upgrade python-snitun --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python-snitun/0.45.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-s390x-38.secgroup --name adt-resolute-s390x-python-snitun-20260208-082158-juju-7f2275-prod-proposed-migration-environment-2-6b600f4c-150d-45ba-818f-940b8e9f30e1 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-s390x-python-snitun-20260208-082158-juju-7f2275-prod-proposed-migration-environment-2-6b600f4c-150d-45ba-818f-940b8e9f30e1 from image adt/ubuntu-resolute-s390x-server-20260208.img (UUID bf8d2066-816d-4293-b7d1-06f39d3d29cb)... 143s autopkgtest [08:24:21]: testbed dpkg architecture: s390x 144s autopkgtest [08:24:22]: testbed apt version: 3.1.15 144s autopkgtest [08:24:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 145s autopkgtest [08:24:23]: testbed release detected to be: None 148s autopkgtest [08:24:26]: updating testbed package index (apt update) 149s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 150s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 150s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 150s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 150s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1521 kB] 150s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [175 kB] 150s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [28.2 kB] 150s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [233 kB] 150s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [1347 kB] 150s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [7384 B] 153s Fetched 3436 kB in 4s (807 kB/s) 159s Reading package lists... 163s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 163s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 163s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 164s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 171s Reading package lists... 172s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s Calculating upgrade... 174s The following packages will be upgraded: 174s libxau6 libxkbcommon0 ubuntu-kernel-accessories ubuntu-standard 175s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 199 kB of archives. 175s After this operation, 4096 B of additional disk space will be used. 175s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libxau6 s390x 1:1.0.11-1build2 [7410 B] 175s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libxkbcommon0 s390x 1.13.1-1 [165 kB] 175s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x ubuntu-standard s390x 1.564 [13.3 kB] 175s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x ubuntu-kernel-accessories s390x 1.564 [13.1 kB] 179s dpkg-preconfigure: unable to re-open stdin: No such file or directory 179s Fetched 199 kB in 0s (2565 kB/s) 187s (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 ... 88711 files and directories currently installed.) 187s Preparing to unpack .../libxau6_1%3a1.0.11-1build2_s390x.deb ... 187s Unpacking libxau6:s390x (1:1.0.11-1build2) over (1:1.0.11-1build1) ... 189s Preparing to unpack .../libxkbcommon0_1.13.1-1_s390x.deb ... 190s Unpacking libxkbcommon0:s390x (1.13.1-1) over (1.12.3-1) ... 191s Preparing to unpack .../ubuntu-standard_1.564_s390x.deb ... 192s Unpacking ubuntu-standard (1.564) over (1.563) ... 193s Preparing to unpack .../ubuntu-kernel-accessories_1.564_s390x.deb ... 194s Unpacking ubuntu-kernel-accessories (1.564) over (1.563) ... 195s Setting up libxau6:s390x (1:1.0.11-1build2) ... 195s Setting up ubuntu-kernel-accessories (1.564) ... 195s Setting up ubuntu-standard (1.564) ... 195s Setting up libxkbcommon0:s390x (1.13.1-1) ... 195s Processing triggers for libc-bin (2.42-2ubuntu4) ... 196s autopkgtest [08:25:14]: upgrading testbed (apt dist-upgrade and autopurge) 197s Reading package lists... 198s Building dependency tree... 198s Reading state information... 199s Calculating upgrade... 199s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 200s Reading package lists... 201s Building dependency tree... 201s Reading state information... 202s Solving dependencies... 203s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 208s autopkgtest [08:25:26]: testbed running kernel: Linux 6.19.0-3-generic #3-Ubuntu SMP Fri Jan 23 19:02:49 UTC 2026 208s autopkgtest [08:25:26]: @@@@@@@@@@@@@@@@@@@@ apt-source python-snitun 212s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/universe python-snitun 0.45.2-1 (dsc) [2375 B] 212s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/universe python-snitun 0.45.2-1 (tar) [67.3 kB] 212s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/universe python-snitun 0.45.2-1 (diff) [3200 B] 214s gpgv: Signature made Sun Feb 1 14:33:07 2026 UTC 214s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 214s gpgv: Can't check signature: No public key 214s dpkg-source: warning: cannot verify inline signature for ./python-snitun_0.45.2-1.dsc: no acceptable signature found 214s autopkgtest [08:25:32]: testing package python-snitun version 0.45.2-1 214s autopkgtest [08:25:32]: build not needed 215s autopkgtest [08:25:33]: test pybuild-autopkgtest: preparing testbed 215s Reading package lists... 216s Building dependency tree... 216s Reading state information... 216s Solving dependencies... 217s The following NEW packages will be installed: 217s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 217s cpp-15-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 217s dh-python dh-strip-nondeterminism dwz g++ g++-15 g++-15-s390x-linux-gnu 217s g++-s390x-linux-gnu gcc gcc-15 gcc-15-s390x-linux-gnu gcc-s390x-linux-gnu 217s gettext intltool-debian libarchive-zip-perl libasan8 libc-dev-bin libc6-dev 217s libcc1-0 libcrypt-dev libdebhelper-perl libfile-stripnondeterminism-perl 217s libgcc-15-dev libgomp1 libisl23 libitm1 liblsan0 libmpc3 libstdc++-15-dev 217s libtool libtsan2 libubsan1 linux-libc-dev m4 po-debconf 217s pybuild-plugin-autopkgtest pybuild-plugin-pyproject python3-aiohappyeyeballs 217s python3-aiohttp python3-aiosignal python3-all python3-async-timeout 217s python3-build python3-frozenlist python3-iniconfig python3-installer 217s python3-multidict python3-pluggy python3-propcache python3-pyproject-hooks 217s python3-pytest python3-pytest-aiohttp python3-pytest-asyncio 217s python3-pytest-timeout python3-snitun python3-wheel python3-yarl python3.14 217s python3.14-minimal rpcsvc-proto 217s 0 upgraded, 71 newly installed, 0 to remove and 0 not upgraded. 217s Need to get 68.6 MB of archives. 217s After this operation, 227 MB of additional disk space will be used. 217s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14-minimal s390x 3.14.2-1 [2510 kB] 218s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x m4 s390x 1.4.20-2 [223 kB] 218s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x autoconf all 2.72-3.1ubuntu1 [384 kB] 218s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x autotools-dev all 20240727.1build1 [43.4 kB] 218s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x automake all 1:1.18.1-3build1 [582 kB] 218s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x autopoint all 0.23.2-1 [620 kB] 218s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libc-dev-bin s390x 2.42-2ubuntu4 [22.9 kB] 218s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x linux-libc-dev s390x 6.19.0-3.3 [1839 kB] 218s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x libcrypt-dev s390x 1:4.5.1-1 [127 kB] 218s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x rpcsvc-proto s390x 1.4.3-1build1 [67.1 kB] 218s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x libc6-dev s390x 2.42-2ubuntu4 [1696 kB] 218s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libisl23 s390x 0.27-1build1 [702 kB] 218s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libmpc3 s390x 1.3.1-2 [57.4 kB] 218s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [10.2 MB] 219s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15 s390x 15.2.0-12ubuntu1 [1028 B] 219s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [5746 B] 219s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x cpp s390x 4:15.2.0-4ubuntu1 [22.4 kB] 219s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x libcc1-0 s390x 15.2.0-12ubuntu1 [50.0 kB] 219s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x libgomp1 s390x 15.2.0-12ubuntu1 [154 kB] 219s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x libitm1 s390x 15.2.0-12ubuntu1 [30.9 kB] 219s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x libasan8 s390x 15.2.0-12ubuntu1 [2968 kB] 219s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x liblsan0 s390x 15.2.0-12ubuntu1 [1354 kB] 219s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x libtsan2 s390x 15.2.0-12ubuntu1 [2685 kB] 219s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x libubsan1 s390x 15.2.0-12ubuntu1 [1211 kB] 219s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-15-dev s390x 15.2.0-12ubuntu1 [1294 kB] 220s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [19.9 MB] 220s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15 s390x 15.2.0-12ubuntu1 [519 kB] 220s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [1208 B] 220s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x gcc s390x 4:15.2.0-4ubuntu1 [5018 B] 220s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++-15-dev s390x 15.2.0-12ubuntu1 [2660 kB] 220s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [11.7 MB] 221s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15 s390x 15.2.0-12ubuntu1 [25.3 kB] 221s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x g++-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [956 B] 221s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x g++ s390x 4:15.2.0-4ubuntu1 [1078 B] 221s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x build-essential s390x 12.12ubuntu2 [5258 B] 221s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 221s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x libtool all 2.5.4-9 [169 kB] 221s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x dh-autoreconf all 21+nmu1 [11.9 kB] 221s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 221s Get:40 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 221s Get:41 http://ftpmaster.internal/ubuntu resolute/main s390x dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 221s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x debugedit s390x 1:5.2-3build1 [52.7 kB] 221s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x dwz s390x 0.16-2build1 [121 kB] 221s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x gettext s390x 0.23.2-1 [1062 kB] 221s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 221s Get:46 http://ftpmaster.internal/ubuntu resolute/main s390x po-debconf all 1.0.22 [215 kB] 221s Get:47 http://ftpmaster.internal/ubuntu resolute/main s390x debhelper all 13.28ubuntu1 [916 kB] 221s Get:48 http://ftpmaster.internal/ubuntu resolute/universe s390x dh-python all 7.20260125 [122 kB] 221s Get:49 http://ftpmaster.internal/ubuntu resolute/universe s390x pybuild-plugin-autopkgtest all 7.20260125 [1744 B] 221s Get:50 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pyproject-hooks all 1.2.0-1 [10.2 kB] 221s Get:51 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-wheel all 0.46.1-2 [22.1 kB] 221s Get:52 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-build all 1.2.2-5 [30.9 kB] 221s Get:53 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-installer all 0.7.0+dfsg1-3 [17.4 kB] 221s Get:54 http://ftpmaster.internal/ubuntu resolute/universe s390x pybuild-plugin-pyproject all 7.20260125 [1712 B] 221s Get:55 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-aiohappyeyeballs all 2.6.1-2 [11.1 kB] 221s Get:56 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-multidict s390x 6.4.3-1build1 [73.5 kB] 221s Get:57 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-propcache s390x 0.3.1-1build1 [56.4 kB] 221s Get:58 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-yarl s390x 1.22.0-1 [105 kB] 221s Get:59 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-async-timeout all 5.0.1-1 [6830 B] 221s Get:60 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-frozenlist s390x 1.8.0-2 [58.2 kB] 221s Get:61 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-aiosignal all 1.4.0-1 [5628 B] 221s Get:62 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-aiohttp s390x 3.13.1-1 [498 kB] 221s Get:63 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14 s390x 3.14.2-1 [816 kB] 221s Get:64 http://ftpmaster.internal/ubuntu resolute/main s390x python3-all s390x 3.13.9-3 [890 B] 221s Get:65 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-iniconfig all 2.1.0-2 [6962 B] 221s Get:66 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pluggy all 1.6.0-2 [21.1 kB] 221s Get:67 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest all 9.0.2-2 [277 kB] 221s Get:68 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-asyncio all 1.3.0-1 [13.8 kB] 221s Get:69 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-aiohttp all 1.1.0-3 [5712 B] 221s Get:70 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-timeout all 2.4.0-2 [22.5 kB] 221s Get:71 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x python3-snitun all 0.45.2-1 [28.4 kB] 224s Fetched 68.6 MB in 4s (17.0 MB/s) 224s Selecting previously unselected package python3.14-minimal. 224s (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 ... 88711 files and directories currently installed.) 224s Preparing to unpack .../00-python3.14-minimal_3.14.2-1_s390x.deb ... 224s Unpacking python3.14-minimal (3.14.2-1) ... 225s Selecting previously unselected package m4. 225s Preparing to unpack .../01-m4_1.4.20-2_s390x.deb ... 225s Unpacking m4 (1.4.20-2) ... 225s Selecting previously unselected package autoconf. 225s Preparing to unpack .../02-autoconf_2.72-3.1ubuntu1_all.deb ... 225s Unpacking autoconf (2.72-3.1ubuntu1) ... 225s Selecting previously unselected package autotools-dev. 225s Preparing to unpack .../03-autotools-dev_20240727.1build1_all.deb ... 225s Unpacking autotools-dev (20240727.1build1) ... 226s Selecting previously unselected package automake. 226s Preparing to unpack .../04-automake_1%3a1.18.1-3build1_all.deb ... 226s Unpacking automake (1:1.18.1-3build1) ... 226s Selecting previously unselected package autopoint. 226s Preparing to unpack .../05-autopoint_0.23.2-1_all.deb ... 226s Unpacking autopoint (0.23.2-1) ... 226s Selecting previously unselected package libc-dev-bin. 226s Preparing to unpack .../06-libc-dev-bin_2.42-2ubuntu4_s390x.deb ... 226s Unpacking libc-dev-bin (2.42-2ubuntu4) ... 226s Selecting previously unselected package linux-libc-dev:s390x. 226s Preparing to unpack .../07-linux-libc-dev_6.19.0-3.3_s390x.deb ... 226s Unpacking linux-libc-dev:s390x (6.19.0-3.3) ... 227s Selecting previously unselected package libcrypt-dev:s390x. 227s Preparing to unpack .../08-libcrypt-dev_1%3a4.5.1-1_s390x.deb ... 227s Unpacking libcrypt-dev:s390x (1:4.5.1-1) ... 227s Selecting previously unselected package rpcsvc-proto. 227s Preparing to unpack .../09-rpcsvc-proto_1.4.3-1build1_s390x.deb ... 227s Unpacking rpcsvc-proto (1.4.3-1build1) ... 227s Selecting previously unselected package libc6-dev:s390x. 227s Preparing to unpack .../10-libc6-dev_2.42-2ubuntu4_s390x.deb ... 227s Unpacking libc6-dev:s390x (2.42-2ubuntu4) ... 228s Selecting previously unselected package libisl23:s390x. 228s Preparing to unpack .../11-libisl23_0.27-1build1_s390x.deb ... 228s Unpacking libisl23:s390x (0.27-1build1) ... 228s Selecting previously unselected package libmpc3:s390x. 228s Preparing to unpack .../12-libmpc3_1.3.1-2_s390x.deb ... 228s Unpacking libmpc3:s390x (1.3.1-2) ... 228s Selecting previously unselected package cpp-15-s390x-linux-gnu. 228s Preparing to unpack .../13-cpp-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 228s Unpacking cpp-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 230s Selecting previously unselected package cpp-15. 230s Preparing to unpack .../14-cpp-15_15.2.0-12ubuntu1_s390x.deb ... 230s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 230s Selecting previously unselected package cpp-s390x-linux-gnu. 230s Preparing to unpack .../15-cpp-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 230s Unpacking cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 230s Selecting previously unselected package cpp. 230s Preparing to unpack .../16-cpp_4%3a15.2.0-4ubuntu1_s390x.deb ... 230s Unpacking cpp (4:15.2.0-4ubuntu1) ... 230s Selecting previously unselected package libcc1-0:s390x. 231s Preparing to unpack .../17-libcc1-0_15.2.0-12ubuntu1_s390x.deb ... 231s Unpacking libcc1-0:s390x (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package libgomp1:s390x. 231s Preparing to unpack .../18-libgomp1_15.2.0-12ubuntu1_s390x.deb ... 231s Unpacking libgomp1:s390x (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package libitm1:s390x. 231s Preparing to unpack .../19-libitm1_15.2.0-12ubuntu1_s390x.deb ... 231s Unpacking libitm1:s390x (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package libasan8:s390x. 231s Preparing to unpack .../20-libasan8_15.2.0-12ubuntu1_s390x.deb ... 231s Unpacking libasan8:s390x (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package liblsan0:s390x. 231s Preparing to unpack .../21-liblsan0_15.2.0-12ubuntu1_s390x.deb ... 231s Unpacking liblsan0:s390x (15.2.0-12ubuntu1) ... 232s Selecting previously unselected package libtsan2:s390x. 232s Preparing to unpack .../22-libtsan2_15.2.0-12ubuntu1_s390x.deb ... 232s Unpacking libtsan2:s390x (15.2.0-12ubuntu1) ... 232s Selecting previously unselected package libubsan1:s390x. 232s Preparing to unpack .../23-libubsan1_15.2.0-12ubuntu1_s390x.deb ... 232s Unpacking libubsan1:s390x (15.2.0-12ubuntu1) ... 232s Selecting previously unselected package libgcc-15-dev:s390x. 233s Preparing to unpack .../24-libgcc-15-dev_15.2.0-12ubuntu1_s390x.deb ... 233s Unpacking libgcc-15-dev:s390x (15.2.0-12ubuntu1) ... 233s Selecting previously unselected package gcc-15-s390x-linux-gnu. 233s Preparing to unpack .../25-gcc-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 233s Unpacking gcc-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 234s Selecting previously unselected package gcc-15. 234s Preparing to unpack .../26-gcc-15_15.2.0-12ubuntu1_s390x.deb ... 234s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 235s Selecting previously unselected package gcc-s390x-linux-gnu. 235s Preparing to unpack .../27-gcc-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 235s Unpacking gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 235s Selecting previously unselected package gcc. 235s Preparing to unpack .../28-gcc_4%3a15.2.0-4ubuntu1_s390x.deb ... 235s Unpacking gcc (4:15.2.0-4ubuntu1) ... 235s Selecting previously unselected package libstdc++-15-dev:s390x. 235s Preparing to unpack .../29-libstdc++-15-dev_15.2.0-12ubuntu1_s390x.deb ... 235s Unpacking libstdc++-15-dev:s390x (15.2.0-12ubuntu1) ... 237s Selecting previously unselected package g++-15-s390x-linux-gnu. 237s Preparing to unpack .../30-g++-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 237s Unpacking g++-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package g++-15. 239s Preparing to unpack .../31-g++-15_15.2.0-12ubuntu1_s390x.deb ... 239s Unpacking g++-15 (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package g++-s390x-linux-gnu. 239s Preparing to unpack .../32-g++-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 239s Unpacking g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package g++. 239s Preparing to unpack .../33-g++_4%3a15.2.0-4ubuntu1_s390x.deb ... 239s Unpacking g++ (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package build-essential. 239s Preparing to unpack .../34-build-essential_12.12ubuntu2_s390x.deb ... 239s Unpacking build-essential (12.12ubuntu2) ... 239s Selecting previously unselected package libdebhelper-perl. 239s Preparing to unpack .../35-libdebhelper-perl_13.28ubuntu1_all.deb ... 239s Unpacking libdebhelper-perl (13.28ubuntu1) ... 239s Selecting previously unselected package libtool. 239s Preparing to unpack .../36-libtool_2.5.4-9_all.deb ... 239s Unpacking libtool (2.5.4-9) ... 239s Selecting previously unselected package dh-autoreconf. 239s Preparing to unpack .../37-dh-autoreconf_21+nmu1_all.deb ... 239s Unpacking dh-autoreconf (21+nmu1) ... 239s Selecting previously unselected package libarchive-zip-perl. 239s Preparing to unpack .../38-libarchive-zip-perl_1.68-1_all.deb ... 239s Unpacking libarchive-zip-perl (1.68-1) ... 239s Selecting previously unselected package libfile-stripnondeterminism-perl. 239s Preparing to unpack .../39-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 239s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 240s Selecting previously unselected package dh-strip-nondeterminism. 240s Preparing to unpack .../40-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 240s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 240s Selecting previously unselected package debugedit. 240s Preparing to unpack .../41-debugedit_1%3a5.2-3build1_s390x.deb ... 240s Unpacking debugedit (1:5.2-3build1) ... 240s Selecting previously unselected package dwz. 240s Preparing to unpack .../42-dwz_0.16-2build1_s390x.deb ... 240s Unpacking dwz (0.16-2build1) ... 240s Selecting previously unselected package gettext. 240s Preparing to unpack .../43-gettext_0.23.2-1_s390x.deb ... 240s Unpacking gettext (0.23.2-1) ... 240s Selecting previously unselected package intltool-debian. 240s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6build1_all.deb ... 240s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 240s Selecting previously unselected package po-debconf. 240s Preparing to unpack .../45-po-debconf_1.0.22_all.deb ... 240s Unpacking po-debconf (1.0.22) ... 240s Selecting previously unselected package debhelper. 241s Preparing to unpack .../46-debhelper_13.28ubuntu1_all.deb ... 241s Unpacking debhelper (13.28ubuntu1) ... 241s Selecting previously unselected package dh-python. 241s Preparing to unpack .../47-dh-python_7.20260125_all.deb ... 241s Unpacking dh-python (7.20260125) ... 241s Selecting previously unselected package pybuild-plugin-autopkgtest. 241s Preparing to unpack .../48-pybuild-plugin-autopkgtest_7.20260125_all.deb ... 241s Unpacking pybuild-plugin-autopkgtest (7.20260125) ... 241s Selecting previously unselected package python3-pyproject-hooks. 241s Preparing to unpack .../49-python3-pyproject-hooks_1.2.0-1_all.deb ... 241s Unpacking python3-pyproject-hooks (1.2.0-1) ... 241s Selecting previously unselected package python3-wheel. 241s Preparing to unpack .../50-python3-wheel_0.46.1-2_all.deb ... 241s Unpacking python3-wheel (0.46.1-2) ... 242s Selecting previously unselected package python3-build. 242s Preparing to unpack .../51-python3-build_1.2.2-5_all.deb ... 242s Unpacking python3-build (1.2.2-5) ... 242s Selecting previously unselected package python3-installer. 242s Preparing to unpack .../52-python3-installer_0.7.0+dfsg1-3_all.deb ... 242s Unpacking python3-installer (0.7.0+dfsg1-3) ... 242s Selecting previously unselected package pybuild-plugin-pyproject. 242s Preparing to unpack .../53-pybuild-plugin-pyproject_7.20260125_all.deb ... 242s Unpacking pybuild-plugin-pyproject (7.20260125) ... 242s Selecting previously unselected package python3-aiohappyeyeballs. 242s Preparing to unpack .../54-python3-aiohappyeyeballs_2.6.1-2_all.deb ... 242s Unpacking python3-aiohappyeyeballs (2.6.1-2) ... 242s Selecting previously unselected package python3-multidict. 242s Preparing to unpack .../55-python3-multidict_6.4.3-1build1_s390x.deb ... 242s Unpacking python3-multidict (6.4.3-1build1) ... 242s Selecting previously unselected package python3-propcache. 242s Preparing to unpack .../56-python3-propcache_0.3.1-1build1_s390x.deb ... 242s Unpacking python3-propcache (0.3.1-1build1) ... 242s Selecting previously unselected package python3-yarl. 242s Preparing to unpack .../57-python3-yarl_1.22.0-1_s390x.deb ... 242s Unpacking python3-yarl (1.22.0-1) ... 242s Selecting previously unselected package python3-async-timeout. 242s Preparing to unpack .../58-python3-async-timeout_5.0.1-1_all.deb ... 242s Unpacking python3-async-timeout (5.0.1-1) ... 242s Selecting previously unselected package python3-frozenlist. 242s Preparing to unpack .../59-python3-frozenlist_1.8.0-2_s390x.deb ... 242s Unpacking python3-frozenlist (1.8.0-2) ... 242s Selecting previously unselected package python3-aiosignal. 242s Preparing to unpack .../60-python3-aiosignal_1.4.0-1_all.deb ... 242s Unpacking python3-aiosignal (1.4.0-1) ... 242s Selecting previously unselected package python3-aiohttp. 242s Preparing to unpack .../61-python3-aiohttp_3.13.1-1_s390x.deb ... 243s Unpacking python3-aiohttp (3.13.1-1) ... 243s Selecting previously unselected package python3.14. 243s Preparing to unpack .../62-python3.14_3.14.2-1_s390x.deb ... 243s Unpacking python3.14 (3.14.2-1) ... 243s Selecting previously unselected package python3-all. 243s Preparing to unpack .../63-python3-all_3.13.9-3_s390x.deb ... 243s Unpacking python3-all (3.13.9-3) ... 243s Selecting previously unselected package python3-iniconfig. 243s Preparing to unpack .../64-python3-iniconfig_2.1.0-2_all.deb ... 243s Unpacking python3-iniconfig (2.1.0-2) ... 243s Selecting previously unselected package python3-pluggy. 244s Preparing to unpack .../65-python3-pluggy_1.6.0-2_all.deb ... 244s Unpacking python3-pluggy (1.6.0-2) ... 244s Selecting previously unselected package python3-pytest. 244s Preparing to unpack .../66-python3-pytest_9.0.2-2_all.deb ... 244s Unpacking python3-pytest (9.0.2-2) ... 244s Selecting previously unselected package python3-pytest-asyncio. 244s Preparing to unpack .../67-python3-pytest-asyncio_1.3.0-1_all.deb ... 244s Unpacking python3-pytest-asyncio (1.3.0-1) ... 244s Selecting previously unselected package python3-pytest-aiohttp. 244s Preparing to unpack .../68-python3-pytest-aiohttp_1.1.0-3_all.deb ... 244s Unpacking python3-pytest-aiohttp (1.1.0-3) ... 244s Selecting previously unselected package python3-pytest-timeout. 244s Preparing to unpack .../69-python3-pytest-timeout_2.4.0-2_all.deb ... 244s Unpacking python3-pytest-timeout (2.4.0-2) ... 244s Selecting previously unselected package python3-snitun. 244s Preparing to unpack .../70-python3-snitun_0.45.2-1_all.deb ... 244s Unpacking python3-snitun (0.45.2-1) ... 245s Setting up dh-python (7.20260125) ... 245s Setting up python3-iniconfig (2.1.0-2) ... 249s Setting up libarchive-zip-perl (1.68-1) ... 249s Setting up libdebhelper-perl (13.28ubuntu1) ... 249s Setting up linux-libc-dev:s390x (6.19.0-3.3) ... 249s Setting up m4 (1.4.20-2) ... 249s Setting up libgomp1:s390x (15.2.0-12ubuntu1) ... 249s Setting up python3-wheel (0.46.1-2) ... 250s Setting up python3-multidict (6.4.3-1build1) ... 251s Setting up python3-frozenlist (1.8.0-2) ... 252s Setting up python3-aiosignal (1.4.0-1) ... 254s Setting up python3-async-timeout (5.0.1-1) ... 255s Setting up autotools-dev (20240727.1build1) ... 255s Setting up rpcsvc-proto (1.4.3-1build1) ... 255s Setting up python3-pyproject-hooks (1.2.0-1) ... 256s Setting up libmpc3:s390x (1.3.1-2) ... 256s Setting up autopoint (0.23.2-1) ... 256s Setting up python3-aiohappyeyeballs (2.6.1-2) ... 257s Setting up python3-installer (0.7.0+dfsg1-3) ... 259s Setting up autoconf (2.72-3.1ubuntu1) ... 259s Setting up python3-pluggy (1.6.0-2) ... 260s Setting up libubsan1:s390x (15.2.0-12ubuntu1) ... 260s Setting up dwz (0.16-2build1) ... 260s Setting up python3-propcache (0.3.1-1build1) ... 261s Setting up libcrypt-dev:s390x (1:4.5.1-1) ... 261s Setting up libasan8:s390x (15.2.0-12ubuntu1) ... 261s Setting up debugedit (1:5.2-3build1) ... 261s Setting up libtsan2:s390x (15.2.0-12ubuntu1) ... 261s Setting up libisl23:s390x (0.27-1build1) ... 261s Setting up python3-build (1.2.2-5) ... 263s Setting up libc-dev-bin (2.42-2ubuntu4) ... 263s Setting up python3-yarl (1.22.0-1) ... 263s Setting up python3.14-minimal (3.14.2-1) ... 275s Setting up libcc1-0:s390x (15.2.0-12ubuntu1) ... 275s Setting up liblsan0:s390x (15.2.0-12ubuntu1) ... 275s Setting up libitm1:s390x (15.2.0-12ubuntu1) ... 275s Setting up automake (1:1.18.1-3build1) ... 275s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 275s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 275s Setting up gettext (0.23.2-1) ... 275s Setting up libgcc-15-dev:s390x (15.2.0-12ubuntu1) ... 275s Setting up pybuild-plugin-pyproject (7.20260125) ... 275s Setting up python3-pytest (9.0.2-2) ... 279s Setting up python3-aiohttp (3.13.1-1) ... 283s Setting up intltool-debian (0.35.0+20060710.6build1) ... 283s Setting up python3-snitun (0.45.2-1) ... 284s Setting up python3-pytest-asyncio (1.3.0-1) ... 285s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 285s Setting up cpp-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 285s Setting up libc6-dev:s390x (2.42-2ubuntu4) ... 285s Setting up python3.14 (3.14.2-1) ... 294s Setting up gcc-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 294s Setting up python3-pytest-aiohttp (1.1.0-3) ... 295s Setting up python3-pytest-timeout (2.4.0-2) ... 297s Setting up cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 297s Setting up po-debconf (1.0.22) ... 297s Setting up python3-all (3.13.9-3) ... 297s Setting up libstdc++-15-dev:s390x (15.2.0-12ubuntu1) ... 297s Setting up gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 297s Setting up cpp-15 (15.2.0-12ubuntu1) ... 297s Setting up cpp (4:15.2.0-4ubuntu1) ... 297s Setting up g++-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 297s Setting up g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 297s Setting up gcc-15 (15.2.0-12ubuntu1) ... 297s Setting up g++-15 (15.2.0-12ubuntu1) ... 297s Setting up libtool (2.5.4-9) ... 297s Setting up gcc (4:15.2.0-4ubuntu1) ... 297s Setting up dh-autoreconf (21+nmu1) ... 297s Setting up g++ (4:15.2.0-4ubuntu1) ... 297s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 297s Setting up build-essential (12.12ubuntu2) ... 297s Setting up debhelper (13.28ubuntu1) ... 297s Setting up pybuild-plugin-autopkgtest (7.20260125) ... 297s Processing triggers for man-db (2.13.1-1build1) ... 308s Processing triggers for install-info (7.2-5) ... 309s Processing triggers for libc-bin (2.42-2ubuntu4) ... 309s Processing triggers for systemd (259-1ubuntu3) ... 312s autopkgtest [08:27:10]: test pybuild-autopkgtest: pybuild-autopkgtest 312s autopkgtest [08:27:10]: test pybuild-autopkgtest: [----------------------- 314s pybuild-autopkgtest 315s I: pybuild base:384: cd /tmp/autopkgtest.gSU56R/autopkgtest_tmp/build; python3.14 -m pytest --ignore=tests/benchmarks 324s ============================= test session starts ============================== 324s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 324s rootdir: /tmp/autopkgtest.gSU56R/autopkgtest_tmp/build 324s configfile: pyproject.toml 324s plugins: timeout-2.4.0, asyncio-1.3.0, typeguard-4.4.4, aiohttp-1.1.0 324s asyncio: mode=Mode.AUTO, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 324s collected 182 items 324s 328s tests/client/test_client_peer.py ............ [ 6%] 330s tests/client/test_connector.py ......... [ 11%] 330s tests/metrics/test_auth_metrics.py .... [ 13%] 330s tests/metrics/test_factory.py . [ 14%] 330s tests/metrics/test_noop.py .. [ 15%] 334s tests/metrics/test_worker_integration.py ....... [ 19%] 335s tests/multiplexer/test_channel.py ............... [ 27%] 335s tests/multiplexer/test_const.py . [ 28%] 340s tests/multiplexer/test_core.py ......................... [ 41%] 340s tests/multiplexer/test_crypto.py . [ 42%] 340s tests/multiplexer/test_message.py ... [ 43%] 341s tests/multiplexer/test_queue.py ....................... [ 56%] 341s tests/server/test_all.py . [ 57%] 343s tests/server/test_listener_peer.py ...... [ 60%] 347s tests/server/test_listener_sni.py ............ [ 67%] 349s tests/server/test_peer.py ...... [ 70%] 349s tests/server/test_peer_manager.py .......... [ 75%] 374s tests/server/test_run.py ...........FFFF [ 84%] 374s tests/server/test_sni.py .......... [ 89%] 384s tests/server/test_worker.py .... [ 91%] 384s tests/utils/test_aes.py .. [ 92%] 384s tests/utils/test_aiohttp_client.py ... [ 94%] 384s tests/utils/test_asyncio.py ...... [ 97%] 384s tests/utils/test_ipaddress.py .. [ 98%] 385s tests/utils/test_server.py .. [100%] 385s 385s =================================== FAILURES =================================== 385s _____________________ test_snitun_worker_runner[payloads3] _____________________ 385s 385s event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False> 385s payloads = [b'\x16\x03\x01\x00H\x01', b'\x00\x00B\x03\x03\xff\xff\xff\xff\xff\xff\xff\xff\xff', b'\xff\xff\xff\xff\xff\xff\xff\xf...ff\xff\xff\x00\x00\x04\x00\x01\x00\xff\x01\x00\x00\x17\x00\x00\x00\x0e\x00\x0c\x00\x00\tlocalhost\x00\x0f\x00\x01\x01'] 385s 385s @pytest.mark.parametrize( 385s "payloads", 385s [ 385s [TLS_1_2], 385s [TLS_1_2[:6], TLS_1_2[6:]], 385s [TLS_1_2[:6], TLS_1_2[6:20], TLS_1_2[20:]], 385s [TLS_1_2[:6], TLS_1_2[6:20], TLS_1_2[20:32], TLS_1_2[32:]], 385s ], 385s ) 385s def test_snitun_worker_runner( 385s event_loop: asyncio.AbstractEventLoop, 385s payloads: list[bytes], 385s ) -> None: 385s """Test SniTunWorker Server runner object.""" 385s loop = event_loop 385s peer_messages = [] 385s peer_address = [] 385s 385s server = SniTunServerWorker( 385s FERNET_TOKENS, 385s host="127.0.0.1", 385s port=32001, 385s worker_size=2, 385s ) 385s server.start() 385s 385s sock = socket.socket(socket.AF_INET, socket.SOCK_STREAM) 385s sock.connect(("127.0.0.1", 32001)) 385s 385s valid = datetime.now(tz=UTC) + timedelta(days=1) 385s aes_key = os.urandom(32) 385s aes_iv = os.urandom(16) 385s hostname = "localhost" 385s fernet_token = create_peer_config(valid.timestamp(), hostname, aes_key, aes_iv) 385s 385s crypto = CryptoTransport(aes_key, aes_iv) 385s 385s sock.sendall(fernet_token) 385s 385s token = sock.recv(32) 385s token = hashlib.sha256(crypto.decrypt(token)).digest() 385s sock.sendall(crypto.encrypt(token)) 385s 385s time.sleep(1) 385s assert any(worker.is_responsible_peer(hostname) for worker in server._workers) 385s assert server.peer_counter == 1 385s 385s async def mock_new_channel( 385s multiplexer: Multiplexer, 385s channel: MultiplexerChannel, 385s ) -> None: 385s """Mock new channel.""" 385s while True: 385s message = await channel.read() 385s peer_messages.append(message) 385s peer_address.append(channel.ip_address) 385s 385s sock_ssl = socket.socket(socket.AF_INET, socket.SOCK_STREAM) 385s sock_ssl.connect(("127.0.0.1", 32001)) 385s 385s async def _create_multiplexer() -> Multiplexer: 385s """Create and return the peer multiplexer.""" 385s reader_peer, writer_peer = await asyncio.open_connection(sock=sock) 385s return Multiplexer( 385s crypto, 385s reader_peer, 385s writer_peer, 385s snitun.PROTOCOL_VERSION, 385s mock_new_channel, 385s ) 385s 385s multiplexer = loop.run_until_complete(_create_multiplexer()) 385s 385s for payload in payloads: 385s sock_ssl.sendall(payload) 385s loop.run_until_complete(asyncio.sleep(0.1)) 385s 385s > assert peer_messages 385s E assert [] 385s 385s tests/server/test_run.py:390: AssertionError 385s ---------------------------- Captured stderr setup ----------------------------- 385s DEBUG:asyncio:Using selector: EpollSelector 385s ------------------------------ Captured log setup ------------------------------ 385s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 385s ----------------------------- Captured stderr call ----------------------------- 385s INFO:snitun.server.run:Run SniTun with 2 worker 385s INFO:snitun.server.run:Server started, fd: 32 385s DEBUG:snitun.server.run:Handover new peer connection: b'gAAAAABpiElnLDs4aVe0hgbLgZfCmLEd2JF5f9ODn8a_-bN16qEk3IPehwxjGPG5oEAMsnSeOTTmb0HaaCr3RdsciUcyKiBVlqlqbgh2Fw969BW9RL9SBHUMIgD4_TNGg0lhHb41FaVW2F7T1u16T8_M5d65nRaKa3N_52S7r6LkXDKvBw5JqMCRXti1V_27xZJlh6oPvUkEB4FsJcMhrFiUOzm_X3-Hfz36U0gXA3pLoBXGWNg5qlELemliYTPQIY8XZGobmnAgJl3wd8EHc7TgTFgY9Hws_JptBIMuWgwF1utOlXbZ7jNfD3FbtSiaoYQsgfdUzczyxaO_4bOG9ccDoC6QXfXIgbRxdi9Ye1u1-s2el_RBwHA=' 385s DEBUG:snitun.server.sni:Can't calculate the TLS record size 385s DEBUG:snitun.server.sni:Can't calculate the TLS record size 385s DEBUG:snitun.server.sni:Can't calculate the TLS record size 385s DEBUG:snitun.multiplexer.queue:Queue creating channel 93006577126182da364816ba56b3b4d1 385s ------------------------------ Captured log call ------------------------------- 385s INFO snitun.server.run:run.py:233 Run SniTun with 2 worker 385s INFO snitun.server.run:run.py:279 Server started, fd: 32 385s DEBUG snitun.server.run:run.py:358 Handover new peer connection: b'gAAAAABpiElnLDs4aVe0hgbLgZfCmLEd2JF5f9ODn8a_-bN16qEk3IPehwxjGPG5oEAMsnSeOTTmb0HaaCr3RdsciUcyKiBVlqlqbgh2Fw969BW9RL9SBHUMIgD4_TNGg0lhHb41FaVW2F7T1u16T8_M5d65nRaKa3N_52S7r6LkXDKvBw5JqMCRXti1V_27xZJlh6oPvUkEB4FsJcMhrFiUOzm_X3-Hfz36U0gXA3pLoBXGWNg5qlELemliYTPQIY8XZGobmnAgJl3wd8EHc7TgTFgY9Hws_JptBIMuWgwF1utOlXbZ7jNfD3FbtSiaoYQsgfdUzczyxaO_4bOG9ccDoC6QXfXIgbRxdi9Ye1u1-s2el_RBwHA=' 385s DEBUG snitun.server.sni:sni.py:66 Can't calculate the TLS record size 385s DEBUG snitun.server.sni:sni.py:66 Can't calculate the TLS record size 385s DEBUG snitun.server.sni:sni.py:66 Can't calculate the TLS record size 385s DEBUG snitun.multiplexer.queue:queue.py:133 Queue creating channel 93006577126182da364816ba56b3b4d1 385s --------------------------- Captured stderr teardown --------------------------- 385s DEBUG:snitun.server.run:Handover localhost to ServerWorker-35 385s __________________________ test_snitun_worker_timeout __________________________ 385s 385s event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False> 385s 385s def test_snitun_worker_timeout(event_loop: asyncio.AbstractEventLoop) -> None: 385s """Test SniTunWorker Server runner object timeout.""" 385s from snitun.server import run 385s 385s run.WORKER_STALE_MAX = 1 385s server = SniTunServerWorker( 385s FERNET_TOKENS, 385s host="127.0.0.1", 385s port=32001, 385s worker_size=2, 385s ) 385s 385s > server.start() 385s 385s tests/server/test_run.py:417: 385s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 385s 385s self = 385s 385s def start(self) -> None: 385s """Run server.""" 385s self._metrics = self._metrics_factory() 385s 385s # Init first all worker, we don't want the epoll on the children 385s _LOGGER.info("Run SniTun with %d worker", self._worker_size) 385s for _ in range(self._worker_size): 385s worker = ServerWorker( 385s self._fernet_keys, 385s throttling=self._throttling, 385s metrics_factory=self._metrics_factory, 385s metrics_interval=self._metrics_interval, 385s ) 385s worker.start() 385s self._workers.append(worker) 385s 385s self._server = socket.socket(socket.AF_INET, socket.SOCK_STREAM) 385s self._server.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 385s > self._server.bind((self._host, self._port)) 385s E OSError: [Errno 98] Address already in use 385s 385s /usr/lib/python3/dist-packages/snitun/server/run.py:246: OSError 385s ---------------------------- Captured stderr setup ----------------------------- 385s DEBUG:asyncio:Using selector: EpollSelector 385s ------------------------------ Captured log setup ------------------------------ 385s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 385s ----------------------------- Captured stderr call ----------------------------- 385s INFO:snitun.server.run:Run SniTun with 2 worker 385s ------------------------------ Captured log call ------------------------------- 385s INFO snitun.server.run:run.py:233 Run SniTun with 2 worker 385s __________________ test_snitun_worker_runner_invalid_payload ___________________ 385s 385s event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False> 385s 385s def test_snitun_worker_runner_invalid_payload( 385s event_loop: asyncio.AbstractEventLoop, 385s ) -> None: 385s """Test SniTunWorker Server runner invalid payload.""" 385s server = SniTunServerWorker( 385s FERNET_TOKENS, 385s host="127.0.0.1", 385s port=32001, 385s worker_size=2, 385s ) 385s > server.start() 385s 385s tests/server/test_run.py:451: 385s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 385s 385s self = 385s 385s def start(self) -> None: 385s """Run server.""" 385s self._metrics = self._metrics_factory() 385s 385s # Init first all worker, we don't want the epoll on the children 385s _LOGGER.info("Run SniTun with %d worker", self._worker_size) 385s for _ in range(self._worker_size): 385s worker = ServerWorker( 385s self._fernet_keys, 385s throttling=self._throttling, 385s metrics_factory=self._metrics_factory, 385s metrics_interval=self._metrics_interval, 385s ) 385s worker.start() 385s self._workers.append(worker) 385s 385s self._server = socket.socket(socket.AF_INET, socket.SOCK_STREAM) 385s self._server.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 385s > self._server.bind((self._host, self._port)) 385s E OSError: [Errno 98] Address already in use 385s 385s /usr/lib/python3/dist-packages/snitun/server/run.py:246: OSError 385s ---------------------------- Captured stderr setup ----------------------------- 385s DEBUG:asyncio:Using selector: EpollSelector 385s ------------------------------ Captured log setup ------------------------------ 385s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 385s ----------------------------- Captured stderr call ----------------------------- 385s INFO:snitun.server.run:Run SniTun with 2 worker 385s ------------------------------ Captured log call ------------------------------- 385s INFO snitun.server.run:run.py:233 Run SniTun with 2 worker 385s ___________________________ test_snitun_worker_crash ___________________________ 385s 385s kill = 385s event_loop = <_UnixSelectorEventLoop running=False closed=False debug=False> 385s 385s @patch("snitun.server.run.os.kill") 385s def test_snitun_worker_crash( 385s kill: MagicMock, 385s event_loop: asyncio.AbstractEventLoop, 385s ) -> None: 385s """Test SniTunWorker Server runner object with crashing worker.""" 385s server = SniTunServerWorker( 385s FERNET_TOKENS, 385s host="127.0.0.1", 385s port=32001, 385s worker_size=2, 385s ) 385s 385s > server.start() 385s 385s tests/server/test_run.py:484: 385s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 385s 385s self = 385s 385s def start(self) -> None: 385s """Run server.""" 385s self._metrics = self._metrics_factory() 385s 385s # Init first all worker, we don't want the epoll on the children 385s _LOGGER.info("Run SniTun with %d worker", self._worker_size) 385s for _ in range(self._worker_size): 385s worker = ServerWorker( 385s self._fernet_keys, 385s throttling=self._throttling, 385s metrics_factory=self._metrics_factory, 385s metrics_interval=self._metrics_interval, 385s ) 385s worker.start() 385s self._workers.append(worker) 385s 385s self._server = socket.socket(socket.AF_INET, socket.SOCK_STREAM) 385s self._server.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 385s > self._server.bind((self._host, self._port)) 385s E OSError: [Errno 98] Address already in use 385s 385s /usr/lib/python3/dist-packages/snitun/server/run.py:246: OSError 385s ---------------------------- Captured stderr setup ----------------------------- 385s DEBUG:asyncio:Using selector: EpollSelector 385s ------------------------------ Captured log setup ------------------------------ 385s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 385s ----------------------------- Captured stderr call ----------------------------- 385s INFO:snitun.server.run:Run SniTun with 2 worker 385s ------------------------------ Captured log call ------------------------------- 385s INFO snitun.server.run:run.py:233 Run SniTun with 2 worker 385s --------------------------- Captured stderr teardown --------------------------- 385s ERROR:asyncio:Task was destroyed but it is pending! 385s task: wait_for=> 385s ERROR:asyncio:Task was destroyed but it is pending! 385s task: wait_for=> 385s ERROR:asyncio:Task was destroyed but it is pending! 385s task: .mock_new_channel() done, defined at /tmp/autopkgtest.gSU56R/autopkgtest_tmp/build/tests/server/test_run.py:360> wait_for= cb=[set.remove()]> 385s ERROR:asyncio:Task was destroyed but it is pending! 385s task: wait_for=> 385s =============================== warnings summary =============================== 385s tests/server/test_run.py::test_snitun_worker_crash 385s /usr/lib/python3/dist-packages/_pytest/unraisableexception.py:67: PytestUnraisableExceptionWarning: Exception ignored while closing generator .mock_new_channel at 0x3ffb2eeb970>: None 385s 385s Traceback (most recent call last): 385s File "/usr/lib/python3.14/asyncio/queues.py", line 186, in get 385s await getter 385s GeneratorExit 385s 385s During handling of the above exception, another exception occurred: 385s 385s Traceback (most recent call last): 385s File "/tmp/autopkgtest.gSU56R/autopkgtest_tmp/build/tests/server/test_run.py", line 366, in mock_new_channel 385s message = await channel.read() 385s ^^^^^^^^^^^^^^^^^^^^ 385s File "/usr/lib/python3/dist-packages/snitun/multiplexer/channel.py", line 270, in read 385s message = await self._input.get() 385s ^^^^^^^^^^^^^^^^^^^^^^^ 385s File "/usr/lib/python3.14/asyncio/queues.py", line 188, in get 385s getter.cancel() # Just in case getter is not done yet. 385s ~~~~~~~~~~~~~^^ 385s File "/usr/lib/python3.14/asyncio/base_events.py", line 827, in call_soon 385s self._check_closed() 385s ~~~~~~~~~~~~~~~~~~^^ 385s File "/usr/lib/python3.14/asyncio/base_events.py", line 550, in _check_closed 385s raise RuntimeError('Event loop is closed') 385s RuntimeError: Event loop is closed 385s 385s 385s warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 385s 385s tests/server/test_run.py::test_snitun_worker_crash 385s /usr/lib/python3/dist-packages/_pytest/unraisableexception.py:67: PytestUnraisableExceptionWarning: Exception ignored while closing generator : None 385s 385s Traceback (most recent call last): 385s File "/usr/lib/python3/dist-packages/snitun/multiplexer/core.py", line 166, in _runner 385s await asyncio.wait( 385s ...<2 lines>... 385s ) 385s GeneratorExit 385s 385s During handling of the above exception, another exception occurred: 385s 385s Traceback (most recent call last): 385s File "/usr/lib/python3/dist-packages/snitun/multiplexer/core.py", line 211, in _runner 385s to_peer.cancel() 385s ~~~~~~~~~~~~~~^^ 385s File "/usr/lib/python3.14/asyncio/base_events.py", line 827, in call_soon 385s self._check_closed() 385s ~~~~~~~~~~~~~~~~~~^^ 385s File "/usr/lib/python3.14/asyncio/base_events.py", line 550, in _check_closed 385s raise RuntimeError('Event loop is closed') 385s RuntimeError: Event loop is closed 385s 385s 385s warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 385s 385s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 385s =========================== short test summary info ============================ 385s FAILED tests/server/test_run.py::test_snitun_worker_runner[payloads3] - asser... 385s FAILED tests/server/test_run.py::test_snitun_worker_timeout - OSError: [Errno... 385s FAILED tests/server/test_run.py::test_snitun_worker_runner_invalid_payload - ... 385s FAILED tests/server/test_run.py::test_snitun_worker_crash - OSError: [Errno 9... 385s ============= 4 failed, 178 passed, 2 warnings in 62.75s (0:01:02) ============= 10319s autopkgtest [11:13:57]: ERROR: timed out on command "su -s /bin/bash ubuntu -c set -e; exec /tmp/autopkgtest.gSU56R/wrapper.sh --artifacts=/tmp/autopkgtest.gSU56R/pybuild-autopkgtest-artifacts --chdir=/tmp/autopkgtest.gSU56R/build.CAa/src --env=AUTOPKGTEST_TESTBED_ARCH=s390x --env=AUTOPKGTEST_TEST_ARCH=s390x --env=DEB_BUILD_OPTIONS=parallel=2 --env=DEBIAN_FRONTEND=noninteractive --env=LANG=C.UTF-8 --unset-env=LANGUAGE --unset-env=LC_ADDRESS --unset-env=LC_ALL --unset-env=LC_COLLATE --unset-env=LC_CTYPE --unset-env=LC_IDENTIFICATION --unset-env=LC_MEASUREMENT --unset-env=LC_MESSAGES --unset-env=LC_MONETARY --unset-env=LC_NAME --unset-env=LC_NUMERIC --unset-env=LC_PAPER --unset-env=LC_TELEPHONE --unset-env=LC_TIME --script-pid-file=/tmp/autopkgtest_script_pid --source-profile --stderr=/tmp/autopkgtest.gSU56R/pybuild-autopkgtest-stderr --stdout=/tmp/autopkgtest.gSU56R/pybuild-autopkgtest-stdout --tmp=/tmp/autopkgtest.gSU56R/autopkgtest_tmp --env=ADT_TEST_TRIGGERS=python-snitun/0.45.2-1 -- bash -ec pybuild-autopkgtest" (kind: test) 10320s autopkgtest [11:13:58]: test pybuild-autopkgtest: -----------------------] 10322s autopkgtest [11:14:00]: test pybuild-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 10322s pybuild-autopkgtest FAIL timed out 10326s autopkgtest [11:14:04]: @@@@@@@@@@@@@@@@@@@@ summary 10326s pybuild-autopkgtest FAIL timed out