0s autopkgtest [07:44:56]: starting date and time: 2026-02-03 07:44:56+0000 0s autopkgtest [07:44:56]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [07:44:56]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.tvftsc7s/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,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:wsl-pro-service --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=wsl-pro-service/0.1.18build1 -- lxd -r lxd-armhf-10.145.243.197 lxd-armhf-10.145.243.197:autopkgtest/ubuntu/resolute/armhf 32s autopkgtest [07:45:28]: testbed dpkg architecture: armhf 36s autopkgtest [07:45:32]: testbed apt version: 3.1.14 40s autopkgtest [07:45:36]: @@@@@@@@@@@@@@@@@@@@ test bed setup 42s autopkgtest [07:45:38]: testbed release detected to be: None 54s autopkgtest [07:45:50]: updating testbed package index (apt update) 58s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 58s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 58s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 58s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 58s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [27.8 kB] 58s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [269 kB] 58s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1270 kB] 59s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 59s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [299 kB] 59s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [1001 kB] 59s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [6868 B] 59s Get:12 http://ftpmaster.internal/ubuntu resolute/multiverse Sources [310 kB] 59s Get:13 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.3 MB] 59s Get:14 http://ftpmaster.internal/ubuntu resolute/main Sources [1404 kB] 59s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1373 kB] 59s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.0 MB] 60s Get:17 http://ftpmaster.internal/ubuntu resolute/multiverse armhf Packages [176 kB] 63s Fetched 42.7 MB in 5s (7962 kB/s) 64s Reading package lists... 72s autopkgtest [07:46:08]: upgrading testbed (apt dist-upgrade and autopurge) 74s Reading package lists... 75s Building dependency tree... 75s Reading state information... 75s Calculating upgrade... 76s The following packages will be upgraded: 76s systemd-hwe-hwdb 76s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 76s Need to get 3152 B of archives. 76s After this operation, 1024 B of additional disk space will be used. 76s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf systemd-hwe-hwdb all 259.0.1 [3152 B] 77s Fetched 3152 B in 0s (10.6 kB/s) 77s (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 ... 68394 files and directories currently installed.) 77s Preparing to unpack .../systemd-hwe-hwdb_259.0.1_all.deb ... 77s Unpacking systemd-hwe-hwdb (259.0.1) over (257.7.1) ... 77s Setting up systemd-hwe-hwdb (259.0.1) ... 78s Processing triggers for udev (259-1ubuntu3) ... 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Solving dependencies... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s autopkgtest [07:46:21]: rebooting testbed after setup commands that affected boot 134s autopkgtest [07:47:10]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2 163s autopkgtest [07:47:39]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 175s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (dsc) [2088 B] 175s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (tar) [1792 kB] 175s gpgv: Signature made Mon Feb 2 20:53:03 2026 UTC 175s gpgv: using RSA key 92FE4F8B40CADC4ECCD60C1F5961FDBE9C619809 175s gpgv: Can't check signature: No public key 175s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.18build1.dsc: no acceptable signature found 176s autopkgtest [07:47:52]: testing package wsl-pro-service version 0.1.18build1 178s autopkgtest [07:47:54]: build not needed 186s autopkgtest [07:48:02]: test command1: preparing testbed 188s Reading package lists... 188s Building dependency tree... 188s Reading state information... 188s Solving dependencies... 189s The following NEW packages will be installed: 189s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 189s cpp-15-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 189s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 189s g++-15-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-15 189s gcc-15-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext golang-1.25-go 189s golang-1.25-src golang-go golang-src intltool-debian libarchive-zip-perl 189s libasan8 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl 189s libdw1t64 libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 189s libmpc3 libstdc++-15-dev libtool libubsan1 linux-libc-dev m4 po-debconf 189s rpcsvc-proto 190s 0 upgraded, 50 newly installed, 0 to remove and 0 not upgraded. 190s Need to get 97.6 MB of archives. 190s After this operation, 375 MB of additional disk space will be used. 190s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf m4 armhf 1.4.20-2 [212 kB] 192s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf autoconf all 2.72-3.1ubuntu1 [384 kB] 192s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf autotools-dev all 20240727.1 [43.4 kB] 193s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf automake all 1:1.18.1-3build1 [582 kB] 194s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf autopoint all 0.23.2-1 [620 kB] 194s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libc-dev-bin armhf 2.42-2ubuntu4 [21.8 kB] 194s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf linux-libc-dev armhf 6.18.0-9.9 [1819 kB] 196s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf libcrypt-dev armhf 1:4.5.1-1 [128 kB] 197s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf rpcsvc-proto armhf 1.4.3-1 [62.3 kB] 197s Get:10 http://ftpmaster.internal/ubuntu resolute/main armhf libc6-dev armhf 2.42-2ubuntu4 [1416 kB] 198s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libisl23 armhf 0.27-1build1 [553 kB] 198s Get:12 http://ftpmaster.internal/ubuntu resolute/main armhf libmpc3 armhf 1.3.1-2 [47.0 kB] 198s Get:13 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [10.1 MB] 207s Get:14 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15 armhf 15.2.0-12ubuntu1 [1032 B] 207s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [5756 B] 207s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf cpp armhf 4:15.2.0-4ubuntu1 [22.4 kB] 207s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libcc1-0 armhf 15.2.0-12ubuntu1 [43.5 kB] 207s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libgomp1 armhf 15.2.0-12ubuntu1 [129 kB] 207s Get:19 http://ftpmaster.internal/ubuntu resolute/main armhf libasan8 armhf 15.2.0-12ubuntu1 [2949 kB] 209s Get:20 http://ftpmaster.internal/ubuntu resolute/main armhf libubsan1 armhf 15.2.0-12ubuntu1 [1187 kB] 209s Get:21 http://ftpmaster.internal/ubuntu resolute/main armhf libgcc-15-dev armhf 15.2.0-12ubuntu1 [898 kB] 209s Get:22 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [19.5 MB] 215s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15 armhf 15.2.0-12ubuntu1 [499 kB] 215s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [1220 B] 215s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf gcc armhf 4:15.2.0-4ubuntu1 [5022 B] 215s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libstdc++-15-dev armhf 15.2.0-12ubuntu1 [2638 kB] 216s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [11.4 MB] 218s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15 armhf 15.2.0-12ubuntu1 [25.3 kB] 218s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf g++-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [968 B] 218s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf g++ armhf 4:15.2.0-4ubuntu1 [1086 B] 218s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf build-essential armhf 12.12ubuntu2 [5256 B] 218s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 218s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libtool all 2.5.4-9 [169 kB] 218s Get:34 http://ftpmaster.internal/ubuntu resolute/main armhf dh-autoreconf all 21 [12.5 kB] 218s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 218s Get:36 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 218s Get:37 http://ftpmaster.internal/ubuntu resolute/main armhf dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 218s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf libdw1t64 armhf 0.194-1 [254 kB] 218s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf debugedit armhf 1:5.2-3 [48.9 kB] 218s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf dwz armhf 0.16-2 [114 kB] 218s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf gettext armhf 0.23.2-1 [1059 kB] 218s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 218s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf po-debconf all 1.0.22 [215 kB] 218s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf debhelper all 13.28ubuntu1 [916 kB] 219s Get:45 http://ftpmaster.internal/ubuntu resolute/universe armhf dh-apport all 2.33.1-0ubuntu3 [20.2 kB] 219s Get:46 http://ftpmaster.internal/ubuntu resolute/main armhf golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 222s Get:47 http://ftpmaster.internal/ubuntu resolute/main armhf golang-1.25-go armhf 1.25.0-0ubuntu1 [16.3 MB] 226s Get:48 http://ftpmaster.internal/ubuntu resolute/main armhf golang-src all 2:1.25~1 [5206 B] 226s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf golang-go armhf 2:1.25~1 [44.1 kB] 226s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf dh-golang all 1.63build1 [25.0 kB] 227s Fetched 97.6 MB in 37s (2637 kB/s) 227s Selecting previously unselected package m4. 227s (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 ... 68395 files and directories currently installed.) 227s Preparing to unpack .../00-m4_1.4.20-2_armhf.deb ... 227s Unpacking m4 (1.4.20-2) ... 227s Selecting previously unselected package autoconf. 227s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 227s Unpacking autoconf (2.72-3.1ubuntu1) ... 227s Selecting previously unselected package autotools-dev. 227s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 227s Unpacking autotools-dev (20240727.1) ... 227s Selecting previously unselected package automake. 227s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ... 227s Unpacking automake (1:1.18.1-3build1) ... 227s Selecting previously unselected package autopoint. 227s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ... 227s Unpacking autopoint (0.23.2-1) ... 227s Selecting previously unselected package libc-dev-bin. 227s Preparing to unpack .../05-libc-dev-bin_2.42-2ubuntu4_armhf.deb ... 227s Unpacking libc-dev-bin (2.42-2ubuntu4) ... 227s Selecting previously unselected package linux-libc-dev:armhf. 227s Preparing to unpack .../06-linux-libc-dev_6.18.0-9.9_armhf.deb ... 227s Unpacking linux-libc-dev:armhf (6.18.0-9.9) ... 228s Selecting previously unselected package libcrypt-dev:armhf. 228s Preparing to unpack .../07-libcrypt-dev_1%3a4.5.1-1_armhf.deb ... 228s Unpacking libcrypt-dev:armhf (1:4.5.1-1) ... 228s Selecting previously unselected package rpcsvc-proto. 228s Preparing to unpack .../08-rpcsvc-proto_1.4.3-1_armhf.deb ... 228s Unpacking rpcsvc-proto (1.4.3-1) ... 228s Selecting previously unselected package libc6-dev:armhf. 228s Preparing to unpack .../09-libc6-dev_2.42-2ubuntu4_armhf.deb ... 228s Unpacking libc6-dev:armhf (2.42-2ubuntu4) ... 228s Selecting previously unselected package libisl23:armhf. 228s Preparing to unpack .../10-libisl23_0.27-1build1_armhf.deb ... 228s Unpacking libisl23:armhf (0.27-1build1) ... 228s Selecting previously unselected package libmpc3:armhf. 228s Preparing to unpack .../11-libmpc3_1.3.1-2_armhf.deb ... 228s Unpacking libmpc3:armhf (1.3.1-2) ... 228s Selecting previously unselected package cpp-15-arm-linux-gnueabihf. 228s Preparing to unpack .../12-cpp-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ... 228s Unpacking cpp-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package cpp-15. 229s Preparing to unpack .../13-cpp-15_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package cpp-arm-linux-gnueabihf. 229s Preparing to unpack .../14-cpp-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 229s Unpacking cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 229s Selecting previously unselected package cpp. 229s Preparing to unpack .../15-cpp_4%3a15.2.0-4ubuntu1_armhf.deb ... 229s Unpacking cpp (4:15.2.0-4ubuntu1) ... 229s Selecting previously unselected package libcc1-0:armhf. 229s Preparing to unpack .../16-libcc1-0_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking libcc1-0:armhf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package libgomp1:armhf. 229s Preparing to unpack .../17-libgomp1_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking libgomp1:armhf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package libasan8:armhf. 229s Preparing to unpack .../18-libasan8_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking libasan8:armhf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package libubsan1:armhf. 229s Preparing to unpack .../19-libubsan1_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking libubsan1:armhf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package libgcc-15-dev:armhf. 229s Preparing to unpack .../20-libgcc-15-dev_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking libgcc-15-dev:armhf (15.2.0-12ubuntu1) ... 229s Selecting previously unselected package gcc-15-arm-linux-gnueabihf. 229s Preparing to unpack .../21-gcc-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ... 229s Unpacking gcc-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 230s Selecting previously unselected package gcc-15. 230s Preparing to unpack .../22-gcc-15_15.2.0-12ubuntu1_armhf.deb ... 230s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 230s Selecting previously unselected package gcc-arm-linux-gnueabihf. 230s Preparing to unpack .../23-gcc-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 230s Unpacking gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 230s Selecting previously unselected package gcc. 230s Preparing to unpack .../24-gcc_4%3a15.2.0-4ubuntu1_armhf.deb ... 230s Unpacking gcc (4:15.2.0-4ubuntu1) ... 230s Selecting previously unselected package libstdc++-15-dev:armhf. 230s Preparing to unpack .../25-libstdc++-15-dev_15.2.0-12ubuntu1_armhf.deb ... 230s Unpacking libstdc++-15-dev:armhf (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package g++-15-arm-linux-gnueabihf. 231s Preparing to unpack .../26-g++-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ... 231s Unpacking g++-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package g++-15. 231s Preparing to unpack .../27-g++-15_15.2.0-12ubuntu1_armhf.deb ... 231s Unpacking g++-15 (15.2.0-12ubuntu1) ... 231s Selecting previously unselected package g++-arm-linux-gnueabihf. 231s Preparing to unpack .../28-g++-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ... 231s Unpacking g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 231s Selecting previously unselected package g++. 231s Preparing to unpack .../29-g++_4%3a15.2.0-4ubuntu1_armhf.deb ... 231s Unpacking g++ (4:15.2.0-4ubuntu1) ... 231s Selecting previously unselected package build-essential. 231s Preparing to unpack .../30-build-essential_12.12ubuntu2_armhf.deb ... 231s Unpacking build-essential (12.12ubuntu2) ... 231s Selecting previously unselected package libdebhelper-perl. 231s Preparing to unpack .../31-libdebhelper-perl_13.28ubuntu1_all.deb ... 231s Unpacking libdebhelper-perl (13.28ubuntu1) ... 231s Selecting previously unselected package libtool. 231s Preparing to unpack .../32-libtool_2.5.4-9_all.deb ... 231s Unpacking libtool (2.5.4-9) ... 231s Selecting previously unselected package dh-autoreconf. 231s Preparing to unpack .../33-dh-autoreconf_21_all.deb ... 231s Unpacking dh-autoreconf (21) ... 231s Selecting previously unselected package libarchive-zip-perl. 231s Preparing to unpack .../34-libarchive-zip-perl_1.68-1_all.deb ... 231s Unpacking libarchive-zip-perl (1.68-1) ... 232s Selecting previously unselected package libfile-stripnondeterminism-perl. 232s Preparing to unpack .../35-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 232s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 232s Selecting previously unselected package dh-strip-nondeterminism. 232s Preparing to unpack .../36-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 232s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 232s Selecting previously unselected package libdw1t64:armhf. 232s Preparing to unpack .../37-libdw1t64_0.194-1_armhf.deb ... 232s Unpacking libdw1t64:armhf (0.194-1) ... 232s Selecting previously unselected package debugedit. 232s Preparing to unpack .../38-debugedit_1%3a5.2-3_armhf.deb ... 232s Unpacking debugedit (1:5.2-3) ... 232s Selecting previously unselected package dwz. 232s Preparing to unpack .../39-dwz_0.16-2_armhf.deb ... 232s Unpacking dwz (0.16-2) ... 232s Selecting previously unselected package gettext. 232s Preparing to unpack .../40-gettext_0.23.2-1_armhf.deb ... 232s Unpacking gettext (0.23.2-1) ... 232s Selecting previously unselected package intltool-debian. 232s Preparing to unpack .../41-intltool-debian_0.35.0+20060710.6build1_all.deb ... 232s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 232s Selecting previously unselected package po-debconf. 232s Preparing to unpack .../42-po-debconf_1.0.22_all.deb ... 232s Unpacking po-debconf (1.0.22) ... 232s Selecting previously unselected package debhelper. 232s Preparing to unpack .../43-debhelper_13.28ubuntu1_all.deb ... 232s Unpacking debhelper (13.28ubuntu1) ... 232s Selecting previously unselected package dh-apport. 232s Preparing to unpack .../44-dh-apport_2.33.1-0ubuntu3_all.deb ... 232s Unpacking dh-apport (2.33.1-0ubuntu3) ... 232s Selecting previously unselected package golang-1.25-src. 232s Preparing to unpack .../45-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 232s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 236s Selecting previously unselected package golang-1.25-go. 236s Preparing to unpack .../46-golang-1.25-go_1.25.0-0ubuntu1_armhf.deb ... 236s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 237s Selecting previously unselected package golang-src. 237s Preparing to unpack .../47-golang-src_2%3a1.25~1_all.deb ... 237s Unpacking golang-src (2:1.25~1) ... 237s Selecting previously unselected package golang-go:armhf. 237s Preparing to unpack .../48-golang-go_2%3a1.25~1_armhf.deb ... 237s Unpacking golang-go:armhf (2:1.25~1) ... 237s Selecting previously unselected package dh-golang. 237s Preparing to unpack .../49-dh-golang_1.63build1_all.deb ... 237s Unpacking dh-golang (1.63build1) ... 237s Setting up libarchive-zip-perl (1.68-1) ... 237s Setting up libdebhelper-perl (13.28ubuntu1) ... 237s Setting up linux-libc-dev:armhf (6.18.0-9.9) ... 237s Setting up m4 (1.4.20-2) ... 237s Setting up libgomp1:armhf (15.2.0-12ubuntu1) ... 237s Setting up dh-apport (2.33.1-0ubuntu3) ... 237s Setting up libdw1t64:armhf (0.194-1) ... 237s Setting up autotools-dev (20240727.1) ... 237s Setting up rpcsvc-proto (1.4.3-1) ... 237s Setting up libmpc3:armhf (1.3.1-2) ... 237s Setting up autopoint (0.23.2-1) ... 237s Setting up autoconf (2.72-3.1ubuntu1) ... 237s Setting up libubsan1:armhf (15.2.0-12ubuntu1) ... 237s Setting up dwz (0.16-2) ... 237s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 237s Setting up libcrypt-dev:armhf (1:4.5.1-1) ... 238s Setting up libasan8:armhf (15.2.0-12ubuntu1) ... 238s Setting up debugedit (1:5.2-3) ... 238s Setting up libisl23:armhf (0.27-1build1) ... 238s Setting up libc-dev-bin (2.42-2ubuntu4) ... 238s Setting up golang-src (2:1.25~1) ... 238s Setting up cpp-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 238s Setting up libcc1-0:armhf (15.2.0-12ubuntu1) ... 238s Setting up cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 238s Setting up automake (1:1.18.1-3build1) ... 238s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 238s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 238s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 238s Setting up gettext (0.23.2-1) ... 238s Setting up libgcc-15-dev:armhf (15.2.0-12ubuntu1) ... 238s Setting up gcc-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 238s Setting up golang-go:armhf (2:1.25~1) ... 238s Setting up intltool-debian (0.35.0+20060710.6build1) ... 238s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 238s Setting up cpp-15 (15.2.0-12ubuntu1) ... 238s Setting up cpp (4:15.2.0-4ubuntu1) ... 238s Setting up libc6-dev:armhf (2.42-2ubuntu4) ... 238s Setting up gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 238s Setting up po-debconf (1.0.22) ... 238s Setting up gcc-15 (15.2.0-12ubuntu1) ... 238s Setting up libstdc++-15-dev:armhf (15.2.0-12ubuntu1) ... 238s Setting up libtool (2.5.4-9) ... 238s Setting up g++-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ... 238s Setting up gcc (4:15.2.0-4ubuntu1) ... 238s Setting up dh-autoreconf (21) ... 238s Setting up g++-15 (15.2.0-12ubuntu1) ... 238s Setting up g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ... 238s Setting up debhelper (13.28ubuntu1) ... 238s Setting up g++ (4:15.2.0-4ubuntu1) ... 238s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 238s Setting up build-essential (12.12ubuntu2) ... 238s Setting up dh-golang (1.63build1) ... 238s Processing triggers for man-db (2.13.1-1) ... 240s Processing triggers for install-info (7.2-5) ... 240s Processing triggers for libc-bin (2.42-2ubuntu4) ... 249s autopkgtest [07:49:05]: test command1: ./debian/tests/test 249s autopkgtest [07:49:05]: test command1: [----------------------- 316s === RUN TestRunSignal 316s === RUN TestRunSignal/Send_SIGINT_exits 316s INFO Starting WSL Pro Service version Dev 316s === RUN TestRunSignal/Send_SIGTERM_exits 316s INFO Starting WSL Pro Service version Dev 316s --- PASS: TestRunSignal (0.21s) 316s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 316s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 316s === RUN TestRun 316s === PAUSE TestRun 316s === CONT TestRun 316s === RUN TestRun/Run_and_exit_successfully 316s === PAUSE TestRun/Run_and_exit_successfully 316s === RUN TestRun/Run_and_return_error 316s === PAUSE TestRun/Run_and_return_error 316s === RUN TestRun/Run_and_return_usage_error 316s === PAUSE TestRun/Run_and_return_usage_error 316s === RUN TestRun/Run_and_usage_error_only_does_not_fail 316s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 316s === CONT TestRun/Run_and_exit_successfully 316s === CONT TestRun/Run_and_return_usage_error 316s === CONT TestRun/Run_and_return_error 316s === CONT TestRun/Run_and_usage_error_only_does_not_fail 316s INFO Starting WSL Pro Service version Dev 316s INFO Starting WSL Pro Service version Dev 316s INFO Starting WSL Pro Service version Dev 316s INFO Starting WSL Pro Service version Dev 316s ERROR context.Background Error requested 316s ERROR context.Background Error requested 316s --- PASS: TestRun (0.00s) 316s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 316s --- PASS: TestRun/Run_and_return_usage_error (0.11s) 316s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.11s) 316s --- PASS: TestRun/Run_and_return_error (0.11s) 316s PASS 316s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.377s 434s === RUN TestHelp 434s --- PASS: TestHelp (0.00s) 434s === RUN TestCompletion 434s --- PASS: TestCompletion (0.00s) 434s === RUN TestVersion 434s --- PASS: TestVersion (0.00s) 434s === RUN TestConfigBadArg 434s --- PASS: TestConfigBadArg (0.00s) 434s === RUN TestConfigArg 434s --- PASS: TestConfigArg (0.01s) 434s === RUN TestConfigAutoDetect 434s time="2026-02-03T07:50:12Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 434s --- PASS: TestConfigAutoDetect (2.12s) 434s === RUN TestNoUsageError 434s time="2026-02-03T07:50:14Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 434s --- PASS: TestNoUsageError (2.79s) 434s === RUN TestUsageError 434s === PAUSE TestUsageError 434s === RUN TestCanQuitWhenExecute 434s === PAUSE TestCanQuitWhenExecute 434s === RUN TestCanQuitTwice 434s === PAUSE TestCanQuitTwice 434s === RUN TestAppCanQuitWithoutExecute 434s === PAUSE TestAppCanQuitWithoutExecute 434s === RUN TestAppRunFailsOnComponentsCreationAndQuit 434s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 434s === RUN TestAppGetRootCmd 434s === PAUSE TestAppGetRootCmd 434s === RUN TestWithProMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithProMock (0.00s) 434s === RUN TestWithWslPathMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithWslPathMock (0.00s) 434s === RUN TestWithWslInfoMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithWslInfoMock (0.00s) 434s === RUN TestWithCmdExeMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithCmdExeMock (0.00s) 434s === CONT TestUsageError 434s === CONT TestAppCanQuitWithoutExecute 434s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 434s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 434s === CONT TestAppGetRootCmd 434s === CONT TestCanQuitTwice 434s === CONT TestCanQuitWhenExecute 434s === CONT TestAppRunFailsOnComponentsCreationAndQuit 434s time="2026-02-03T07:50:18Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43269" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:74 New() Building new daemon" 434s time="2026-02-03T07:50:18Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33987" 434s time="2026-02-03T07:50:18Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3753388654/b385/wsl-pro-service.yaml" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:74 New() Building new daemon" 434s time="2026-02-03T07:50:18Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44565" 434s time="2026-02-03T07:50:18Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3753388654/b385/wsl-pro-service.yaml" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 434s time="2026-02-03T07:50:18Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:74 New() Building new daemon" 434s --- PASS: TestAppGetRootCmd (1.73s) 434s --- PASS: TestUsageError (2.29s) 434s time="2026-02-03T07:51:21Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 434s time="2026-02-03T07:51:21Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:51:21Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:51:21Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 434s time="2026-02-03T07:51:21Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7076" 434s time="2026-02-03T07:51:22Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 434s time="2026-02-03T07:51:22Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 434s time="2026-02-03T07:51:22Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7076" 434s time="2026-02-03T07:51:23Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:51:23Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:51:25Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 434s time="2026-02-03T07:51:25Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 434s time="2026-02-03T07:51:25Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7076" 434s time="2026-02-03T07:51:47Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:33987" 434s time="2026-02-03T07:51:47Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 434s time="2026-02-03T07:51:47Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 434s time="2026-02-03T07:51:47Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 434s time="2026-02-03T07:51:49Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:44565" 434s time="2026-02-03T07:51:49Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 434s time="2026-02-03T07:51:49Z" level=warning msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:194 (*Daemon).serveOnce() Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:44565: connect: connection refused\"" 434s time="2026-02-03T07:51:49Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 434s time="2026-02-03T07:51:49Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s time="2026-02-03T07:51:49Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:51:49Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:51:49Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s --- PASS: TestCanQuitWhenExecute (92.83s) 434s time="2026-02-03T07:51:54Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:51:54Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:51:54Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (97.64s) 434s time="2026-02-03T07:52:07Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 434s time="2026-02-03T07:52:07Z" level=warning msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:196 (*Daemon).serveOnce() Daemon: disconnected from Windows host" 434s time="2026-02-03T07:52:07Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 434s time="2026-02-03T07:52:07Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s time="2026-02-03T07:52:07Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:52:07Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:52:07Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s time="2026-02-03T07:52:07Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Og0ciS/build.XK9/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 434s time="2026-02-03T07:52:07Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Og0ciS/build.XK9/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 434s time="2026-02-03T07:52:07Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Og0ciS/build.XK9/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 434s time="2026-02-03T07:52:08Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 434s time="2026-02-03T07:52:08Z" level=info msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 434s time="2026-02-03T07:52:08Z" level=debug msg="/tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 434s --- PASS: TestCanQuitTwice (112.35s) 434s PASS 434s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 117.360s 434s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 434s === RUN TestApplyProToken 434s === PAUSE TestApplyProToken 434s === RUN TestApplyLandscapeConfig 434s === PAUSE TestApplyLandscapeConfig 434s === RUN TestWithProMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithProMock (0.00s) 434s === RUN TestWithLandscapeConfigMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithLandscapeConfigMock (0.00s) 434s === RUN TestWithWslPathMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithWslPathMock (0.00s) 434s === RUN TestWithWslInfoMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithWslInfoMock (0.00s) 434s === RUN TestWithCmdExeMock 434s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 434s --- SKIP: TestWithCmdExeMock (0.00s) 434s === CONT TestApplyProToken 434s === RUN TestApplyProToken/Success_attaching 434s === PAUSE TestApplyProToken/Success_attaching 434s === RUN TestApplyProToken/Success_detaching 434s === PAUSE TestApplyProToken/Success_detaching 434s === RUN TestApplyProToken/Error_calling_pro_detach 434s === PAUSE TestApplyProToken/Error_calling_pro_detach 434s === RUN TestApplyProToken/Error_calling_pro_attach 434s === PAUSE TestApplyProToken/Error_calling_pro_attach 434s === CONT TestApplyProToken/Success_attaching 434s === CONT TestApplyProToken/Error_calling_pro_attach 434s === CONT TestApplyLandscapeConfig 434s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 434s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 434s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 434s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 434s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 434s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 434s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 434s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 434s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 434s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 434s === CONT TestApplyProToken/Error_calling_pro_detach 434s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 434s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 434s === CONT TestApplyProToken/Success_detaching 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyProToken: Received empty token: detaching" 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 434s time="2026-02-03T07:50:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 434s --- PASS: TestApplyLandscapeConfig (0.00s) 434s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (28.89s) 434s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (28.98s) 434s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (29.71s) 434s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (31.61s) 434s --- PASS: TestApplyProToken (0.01s) 434s --- PASS: TestApplyProToken/Success_detaching (27.68s) 434s --- PASS: TestApplyProToken/Error_calling_pro_detach (30.74s) 434s --- PASS: TestApplyProToken/Success_attaching (55.30s) 434s --- PASS: TestApplyProToken/Error_calling_pro_attach (56.72s) 434s PASS 434s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 56.862s 434s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 574s === RUN TestNew 574s === PAUSE TestNew 574s === RUN TestServe 574s === PAUSE TestServe 574s === RUN TestServeAndQuit 574s === PAUSE TestServeAndQuit 574s === RUN TestRetryLogic 574s === PAUSE TestRetryLogic 574s === RUN TestReconnection 574s === PAUSE TestReconnection 574s === RUN TestWithProMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithProMock (0.00s) 574s === RUN TestWithWslPathMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslPathMock (0.00s) 574s === RUN TestWithWslInfoMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslInfoMock (0.00s) 574s === RUN TestWithCmdExeMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithCmdExeMock (0.00s) 574s === CONT TestNew 574s === RUN TestNew/Success 574s === PAUSE TestNew/Success 574s === RUN TestNew/Error_when_WslPath_returns_error 574s === PAUSE TestNew/Error_when_WslPath_returns_error 574s === CONT TestNew/Success 574s === CONT TestServeAndQuit 574s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 574s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 574s === RUN TestServeAndQuit/Success_with_graceful_quit 574s === PAUSE TestServeAndQuit/Success_with_graceful_quit 574s === RUN TestServeAndQuit/Success_with_forceful_quit 574s === PAUSE TestServeAndQuit/Success_with_forceful_quit 574s === RUN TestServeAndQuit/Success_with_double_quit 574s === PAUSE TestServeAndQuit/Success_with_double_quit 574s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 574s === CONT TestReconnection 574s === RUN TestReconnection/Success_connecting_after_failing_to_connect 574s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 574s === CONT TestRetryLogic 574s === CONT TestServeAndQuit/Success_with_double_quit 574s === RUN TestRetryLogic/Error_only_when_action_errors 574s === PAUSE TestRetryLogic/Error_only_when_action_errors 574s === RUN TestRetryLogic/Without_retries 574s === PAUSE TestRetryLogic/Without_retries 574s === RUN TestRetryLogic/With_the_context_pre-cancelled 574s === PAUSE TestRetryLogic/With_the_context_pre-cancelled 574s === RUN TestRetryLogic/With_the_context_cancelled_while_waiting 574s === PAUSE TestRetryLogic/With_the_context_cancelled_while_waiting 574s === RUN TestRetryLogic/When_max_attempts_are_exhausted 574s === PAUSE TestRetryLogic/When_max_attempts_are_exhausted 574s === CONT TestNew/Error_when_WslPath_returns_error 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s === CONT TestServeAndQuit/Success_with_forceful_quit 574s === CONT TestServeAndQuit/Success_with_graceful_quit 574s === CONT TestServe 574s === RUN TestServe/No_connection_because_there_are_no_certificates 574s === PAUSE TestServe/No_connection_because_there_are_no_certificates 574s === RUN TestServe/Error_because_the_notifier_returns_an_error 574s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 574s === RUN TestServe/Success 574s === PAUSE TestServe/Success 574s === RUN TestServe/Success_with_a_broken_Landscape_config 574s === PAUSE TestServe/Success_with_a_broken_Landscape_config 574s === RUN TestServe/No_connection_because_the_port_file_has_port_0 574s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 574s === RUN TestServe/No_connection_because_there_is_no_server 574s === PAUSE TestServe/No_connection_because_there_is_no_server 574s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 574s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 574s === RUN TestServe/Error_because_the_context_is_pre-cancelled 574s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 574s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 574s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 574s === RUN TestServe/Success_with_systemd_notifier_returning_true 574s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 574s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 574s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 574s === RUN TestServe/No_connection_because_the_port_file_is_empty 574s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 574s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 574s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 574s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 574s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 574s === CONT TestRetryLogic/Error_only_when_action_errors 574s === CONT TestRetryLogic/With_the_context_pre-cancelled 574s === CONT TestRetryLogic/Without_retries 574s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 574s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 574s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 574s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 574s === CONT TestRetryLogic/When_max_attempts_are_exhausted 574s time="2026-02-03T07:50:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41139" 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:50:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41979" 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:50:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44191" 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:50:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39007" 574s time="2026-02-03T07:50:12Z" level=debug msg="Building new daemon" 574s === CONT TestRetryLogic/With_the_context_cancelled_while_waiting 574s === CONT TestServe/No_connection_because_there_are_no_certificates 574s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 574s --- PASS: TestRetryLogic (0.00s) 574s --- PASS: TestRetryLogic/Error_only_when_action_errors (0.00s) 574s --- PASS: TestRetryLogic/With_the_context_pre-cancelled (0.00s) 574s --- PASS: TestRetryLogic/When_max_attempts_are_exhausted (0.46s) 574s --- PASS: TestRetryLogic/With_the_context_cancelled_while_waiting (0.03s) 574s --- PASS: TestRetryLogic/Without_retries (0.71s) 574s time="2026-02-03T07:50:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45873" 574s time="2026-02-03T07:50:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36637" 574s time="2026-02-03T07:50:14Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:50:14Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:09Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:51:09Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:09Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:09Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:09Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 574s time="2026-02-03T07:51:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41435" 574s time="2026-02-03T07:51:10Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:12Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:12Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:12Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:12Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:12Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s === CONT TestServe/No_connection_because_the_port_file_is_empty 574s time="2026-02-03T07:51:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45665" 574s time="2026-02-03T07:51:12Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:13Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:51:13Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:13Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:13Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:13Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s --- PASS: TestNew (0.00s) 574s --- PASS: TestNew/Error_when_WslPath_returns_error (57.71s) 574s --- PASS: TestNew/Success (62.68s) 574s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 574s time="2026-02-03T07:51:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39213" 574s time="2026-02-03T07:51:15Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:15Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:15Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:19Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:51:19Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:19Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:19Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:19Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:51:20Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:20Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:20Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:20Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:-5\": port cannot be negative" 574s time="2026-02-03T07:51:20Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:51:21Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:21Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:21Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/Success_with_systemd_notifier_returning_true 574s time="2026-02-03T07:51:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42727" 574s time="2026-02-03T07:51:22Z" level=debug msg="Building new daemon" 574s === NAME TestServeAndQuit/Success_with_graceful_quit 574s daemon_test.go:296: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon_test.go:296 574s Error: Condition never satisfied 574s Test: TestServeAndQuit/Success_with_graceful_quit 574s Messages: Systemd state should have been set to "STATUS=Connected" 574s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 574s time="2026-02-03T07:51:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41077" 574s time="2026-02-03T07:51:30Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:32Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s === NAME TestServeAndQuit/Success_with_double_quit 574s daemon_test.go:296: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon_test.go:296 574s Error: Condition never satisfied 574s Test: TestServeAndQuit/Success_with_double_quit 574s Messages: Systemd state should have been set to "STATUS=Connected" 574s === CONT TestServe/Error_because_the_context_is_pre-cancelled 574s time="2026-02-03T07:51:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42031" 574s time="2026-02-03T07:51:35Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:36Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:51:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45873" 574s time="2026-02-03T07:51:36Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates1176925474/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 574s time="2026-02-03T07:51:36Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:51:37Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:37Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:37Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 574s time="2026-02-03T07:51:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40587" 574s time="2026-02-03T07:51:38Z" level=debug msg="Building new daemon" 574s === NAME TestServeAndQuit/Success_with_forceful_quit 574s daemon_test.go:296: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon_test.go:296 574s Error: Condition never satisfied 574s Test: TestServeAndQuit/Success_with_forceful_quit 574s Messages: Systemd state should have been set to "STATUS=Connected" 574s --- FAIL: TestServeAndQuit (0.00s) 574s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (59.72s) 574s --- FAIL: TestServeAndQuit/Success_with_graceful_quit (77.86s) 574s --- FAIL: TestServeAndQuit/Success_with_double_quit (82.12s) 574s --- FAIL: TestServeAndQuit/Success_with_forceful_quit (87.34s) 574s === CONT TestServe/No_connection_because_there_is_no_server 574s time="2026-02-03T07:51:39Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45739" 574s time="2026-02-03T07:51:40Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:43Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:51:56Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:56Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:56Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:56Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:portyMcPortface\": could not parse port as an integer: strconv.Atoi: parsing \"portyMcPortface\": invalid syntax" 574s time="2026-02-03T07:51:56Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:51:57Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:57Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:57Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/No_connection_because_the_port_file_has_port_0 574s time="2026-02-03T07:51:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44039" 574s time="2026-02-03T07:51:57Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:51:58Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:51:58Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:51:58Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:51:58Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_the_port_file_does_not_exist1816290977/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist1816290977/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 574s time="2026-02-03T07:51:58Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:51:59Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:51:59Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:51:59Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/Success_with_a_broken_Landscape_config 574s time="2026-02-03T07:52:00Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:00Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:00Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"\": could not split address: missing port in address" 574s time="2026-02-03T07:52:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34803" 574s time="2026-02-03T07:52:00Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:01Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:01Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:01Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/Success 574s time="2026-02-03T07:52:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43121" 574s time="2026-02-03T07:52:02Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:05Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:52:05Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:06Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:06Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:06Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:52:09Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:09Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:09Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:14Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:14Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:14Z" level=debug msg="All connections have now ended." 574s === CONT TestServe/Error_because_the_notifier_returns_an_error 574s time="2026-02-03T07:52:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36281" 574s time="2026-02-03T07:52:14Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:18Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:18Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:18Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:21Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:21Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:21Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:23Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42727" 574s time="2026-02-03T07:52:23Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:52:23Z" level=debug msg="Updated systemd status to \"Connected\"" 574s time="2026-02-03T07:52:23Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:52:23Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:52:24Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:24Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:24Z" level=debug msg="All connections have now ended." 574s === CONT TestReconnection/Success_connecting_after_failing_to_connect 574s time="2026-02-03T07:52:25Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:30Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:30Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:30Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:30Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:0\": port cannot be zero" 574s time="2026-02-03T07:52:30Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:31Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:31Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:31Z" level=debug msg="All connections have now ended." 574s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 574s time="2026-02-03T07:52:32Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:33Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:33Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: read /tmp/TestServeSuccess_with_a_broken_Landscape_config731821276/001/etc/landscape/client.conf: is a directory" 574s time="2026-02-03T07:52:33Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:34Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40587" 574s time="2026-02-03T07:52:34Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_2184464255/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 574s time="2026-02-03T07:52:34Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:34Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:34Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:34Z" level=debug msg="All connections have now ended." 574s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 574s time="2026-02-03T07:52:34Z" level=debug msg="Building new daemon" 574s time="2026-02-03T07:52:37Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45739" 574s time="2026-02-03T07:52:37Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:52:37Z" level=warning msg="Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:45739: connect: connection refused\"" 574s time="2026-02-03T07:52:37Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:38Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:38Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:38Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:52:38Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:38Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:38Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:38Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:52:38Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:52:38Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:52:38Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s time="2026-02-03T07:52:39Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:39Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:39Z" level=warning msg="Daemon: disconnected from Windows host" 574s time="2026-02-03T07:52:39Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:39Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 574s time="2026-02-03T07:52:39Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:52:39Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:52:39Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:52:39Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:52:39Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:52:45Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 574s time="2026-02-03T07:52:45Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:52:45Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:52:45Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:52:47Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34803" 574s time="2026-02-03T07:52:47Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:52:47Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:52:47Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:52:51Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43121" 574s time="2026-02-03T07:52:51Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:52:51Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:52:51Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:52:54Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:52:54Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:54Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:54Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:54Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:52:54Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_failing_to_connect931328909/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect931328909/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 574s time="2026-02-03T07:52:54Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:52:54Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 574s time="2026-02-03T07:52:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33393" 574s time="2026-02-03T07:52:55Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:55Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:52:58Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39925" 574s time="2026-02-03T07:52:58Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:52:58Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:52:58Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:52:58Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:52:58Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:53:00Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:53:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:53:00Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:53:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s time="2026-02-03T07:53:01Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:53:01Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:53:01Z" level=warning msg="Daemon: disconnected from Windows host" 574s time="2026-02-03T07:53:01Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:53:01Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:53:01Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:01Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:01Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:04Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:53:04Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:53:04Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s time="2026-02-03T07:53:04Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:53:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35679" 574s time="2026-02-03T07:53:04Z" level=debug msg="Ready state sent to systemd" 574s time="2026-02-03T07:53:04Z" level=debug msg="Checking cloud-init status" 574s time="2026-02-03T07:53:04Z" level=debug msg="No Landscape configuration detected: nothing to do" 574s time="2026-02-03T07:53:04Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:53:04Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:53:06Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:53:06Z" level=info msg="Waiting for active requests to close." 574s time="2026-02-03T07:53:06Z" level=warning msg="Daemon: disconnected from Windows host" 574s time="2026-02-03T07:53:06Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:53:06Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:53:06Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s --- PASS: TestServe (0.00s) 574s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (69.39s) 574s --- PASS: TestServe/No_connection_because_there_are_no_certificates (85.16s) 574s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (47.46s) 574s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (45.39s) 574s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (50.65s) 574s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (40.29s) 574s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (55.54s) 574s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (34.75s) 574s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (56.89s) 574s --- PASS: TestServe/No_connection_because_there_is_no_server (58.82s) 574s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (77.61s) 574s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (31.07s) 574s --- PASS: TestServe/Success_with_a_broken_Landscape_config (61.44s) 574s --- PASS: TestServe/Success (63.64s) 574s time="2026-02-03T07:53:06Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:06Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:07Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33393" 574s time="2026-02-03T07:53:07Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:53:07Z" level=debug msg="Updated systemd status to \"Connected\"" 574s time="2026-02-03T07:53:07Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:53:07Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:53:08Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39925" 574s time="2026-02-03T07:53:08Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:53:08Z" level=debug msg="Updated systemd status to \"Connected\"" 574s time="2026-02-03T07:53:09Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:53:09Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:53:14Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35679" 574s time="2026-02-03T07:53:14Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:53:14Z" level=debug msg="Updated systemd status to \"Connected\"" 574s time="2026-02-03T07:53:14Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:53:14Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s === NAME TestReconnection/Success_connecting_after_failing_to_connect 574s daemon_test.go:465: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon_test.go:465 574s Error: Condition never satisfied 574s Test: TestReconnection/Success_connecting_after_failing_to_connect 574s Messages: Daemon never connected to agent's service 574s time="2026-02-03T07:53:15Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:53:15Z" level=info msg="Stopping active requests." 574s time="2026-02-03T07:53:15Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:15Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:15Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:17Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:53:17Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:53:18Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:53:18Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:53:18Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:53:18Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s time="2026-02-03T07:53:24Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:53:24Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:53:24Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:53:24Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s === NAME TestReconnection/Success_connecting_after_previous_connection_dropped 574s daemon_test.go:448: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/daemon/daemon_test.go:448 574s Error: Condition never satisfied 574s Test: TestReconnection/Success_connecting_after_previous_connection_dropped 574s Messages: Daemon never connected to agent's service 574s time="2026-02-03T07:53:24Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:24Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:53:24Z" level=info msg="Stopping active requests." 574s time="2026-02-03T07:53:24Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:24Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:53:24Z" level=warning msg="Daemon: disconnected from Windows host" 574s time="2026-02-03T07:53:24Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:53:24Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 574s time="2026-02-03T07:53:24Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:53:24Z" level=debug msg="All connections have now ended." 574s time="2026-02-03T07:54:23Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:23Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:23Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:23Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF\ncould not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 574s time="2026-02-03T07:54:23Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:54:23Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:54:23Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_co3564294381/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_co3564294381/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 574s time="2026-02-03T07:54:23Z" level=info msg="Reconnecting to Windows host in 1 seconds" 574s time="2026-02-03T07:54:23Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 574s time="2026-02-03T07:54:23Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45315" 574s time="2026-02-03T07:54:24Z" level=info msg="Daemon: connecting to Windows Agent from PID 7078" 574s time="2026-02-03T07:54:24Z" level=debug msg="Updated systemd status to \"Connecting\"" 574s time="2026-02-03T07:54:27Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45315" 574s time="2026-02-03T07:54:27Z" level=info msg="Daemon: completed connection to Windows Agent" 574s time="2026-02-03T07:54:27Z" level=debug msg="Updated systemd status to \"Connected\"" 574s time="2026-02-03T07:54:27Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 574s time="2026-02-03T07:54:27Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 574s time="2026-02-03T07:54:29Z" level=debug msg="Server: sent preface messages to all streams" 574s time="2026-02-03T07:54:29Z" level=info msg="MockWindowsAgent: Connected ready" 574s time="2026-02-03T07:54:29Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 574s time="2026-02-03T07:54:29Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 574s time="2026-02-03T07:54:29Z" level=info msg="Stopping daemon requested." 574s time="2026-02-03T07:54:29Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:29Z" level=info msg="Stopping active requests." 574s time="2026-02-03T07:54:29Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:29Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:54:29Z" level=warning msg="Daemon: disconnected from Windows host" 574s time="2026-02-03T07:54:29Z" level=info msg="Reconnecting to Windows host in 2 seconds" 574s time="2026-02-03T07:54:29Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 574s time="2026-02-03T07:54:29Z" level=debug msg="Updated systemd status to \"Stopped\"" 574s time="2026-02-03T07:54:29Z" level=debug msg="All connections have now ended." 574s --- FAIL: TestReconnection (0.02s) 574s --- FAIL: TestReconnection/Success_connecting_after_failing_to_connect (52.57s) 574s --- FAIL: TestReconnection/Success_connecting_after_previous_connection_dropped (49.62s) 574s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (117.67s) 574s FAIL 574s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 257.657s 574s === RUN TestConnect 574s === PAUSE TestConnect 574s === RUN TestSendAndRecv 574s --- PASS: TestSendAndRecv (0.45s) 574s === RUN TestServe 574s === PAUSE TestServe 574s === RUN TestStop 574s === PAUSE TestStop 574s === RUN TestWithProMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithProMock (0.00s) 574s === RUN TestWithWslPathMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslPathMock (0.00s) 574s === RUN TestWithWslInfoMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslInfoMock (0.00s) 574s === RUN TestWithCmdExeMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithCmdExeMock (0.00s) 574s === CONT TestConnect 574s === RUN TestConnect/Success 574s === PAUSE TestConnect/Success 574s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 574s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 574s === CONT TestConnect/Success 574s === CONT TestServe 574s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 574s === CONT TestStop 574s time="2026-02-03T07:50:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36205" 574s time="2026-02-03T07:50:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43941" 574s --- PASS: TestConnect (0.00s) 574s --- PASS: TestConnect/Success (0.35s) 574s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.03s) 574s === NAME TestServe 574s server_test.go:41: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/streams/server_test.go:41 574s Error: Condition never satisfied 574s Test: TestServe 574s Messages: Setup: Agent service never became ready 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s --- FAIL: TestServe (20.65s) 574s === NAME TestStop 574s server_test.go:111: 574s Error Trace: /tmp/autopkgtest.Og0ciS/build.XK9/src/internal/streams/server_test.go:111 574s Error: Condition never satisfied 574s Test: TestStop 574s Messages: Setup: Agent service never became ready 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s time="2026-02-03T07:50:33Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 574s --- FAIL: TestStop (21.32s) 574s FAIL 574s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 21.873s 574s === RUN TestNew 574s === PAUSE TestNew 574s === RUN TestInfo 574s === PAUSE TestInfo 574s === RUN TestWslDistroName 574s === PAUSE TestWslDistroName 574s === RUN TestUserProfileDir 574s === PAUSE TestUserProfileDir 574s === RUN TestProStatus 574s === PAUSE TestProStatus 574s === RUN TestProAttach 574s === PAUSE TestProAttach 574s === RUN TestProDetach 574s === PAUSE TestProDetach 574s === RUN TestLandscapeEnable 574s === PAUSE TestLandscapeEnable 574s === RUN TestWindowsHostAddress 574s === PAUSE TestWindowsHostAddress 574s === RUN TestLandscapeDisable 574s === PAUSE TestLandscapeDisable 574s === RUN TestEnsureValidLandscapeConfig 574s === PAUSE TestEnsureValidLandscapeConfig 574s === RUN TestRealBackend 574s === PAUSE TestRealBackend 574s === RUN TestWithProMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithProMock (0.00s) 574s === RUN TestWithLandscapeConfigMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithLandscapeConfigMock (0.00s) 574s === RUN TestWithWslPathMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslPathMock (0.00s) 574s === RUN TestWithWslInfoMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithWslInfoMock (0.00s) 574s === RUN TestWithCmdExeMock 574s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 574s --- SKIP: TestWithCmdExeMock (0.00s) 574s === CONT TestNew 574s === RUN TestNew/Return_a_new_system 574s === PAUSE TestNew/Return_a_new_system 574s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 574s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 574s === CONT TestNew/Return_a_new_system 574s === CONT TestRealBackend 574s --- PASS: TestRealBackend (0.00s) 574s === CONT TestEnsureValidLandscapeConfig 574s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 574s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 574s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 574s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 574s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 574s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 574s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 574s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 574s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 574s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 574s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 574s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 574s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 574s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 574s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 574s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 574s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 574s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 574s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 574s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 574s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 574s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 574s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 574s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 574s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 574s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 574s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 574s === CONT TestLandscapeDisable 574s === RUN TestLandscapeDisable/Success 574s === PAUSE TestLandscapeDisable/Success 574s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 574s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 574s === CONT TestLandscapeDisable/Success 574s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 574s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 574s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_due_whitespace_changes 574s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 574s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 574s === CONT TestWslDistroName 574s === RUN TestWslDistroName/Success_using_wslpath 574s === PAUSE TestWslDistroName/Success_using_wslpath 574s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 574s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 574s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 574s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 574s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 574s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 574s === CONT TestWindowsHostAddress 574s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 574s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 574s === RUN TestWindowsHostAddress/Without_NAT 574s === PAUSE TestWindowsHostAddress/Without_NAT 574s === RUN TestWindowsHostAddress/With_NAT 574s === PAUSE TestWindowsHostAddress/With_NAT 574s === CONT TestLandscapeEnable 574s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 574s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 574s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 574s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 574s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 574s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 574s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 574s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 574s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 574s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 574s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 574s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 574s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 574s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 574s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 574s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 574s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 574s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 574s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 574s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 574s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 574s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 574s === RUN TestLandscapeEnable/Regular_with_additional_keys 574s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 574s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 574s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 574s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 574s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 574s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 574s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 574s === CONT TestProDetach 574s === RUN TestProDetach/success_on_unattached_distro 574s === PAUSE TestProDetach/success_on_unattached_distro 574s === RUN TestProDetach/success_on_attached_distro 574s === PAUSE TestProDetach/success_on_attached_distro 574s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 574s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 574s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 574s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 574s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 574s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 574s === CONT TestProAttach 574s === RUN TestProAttach/success 574s === PAUSE TestProAttach/success 574s === RUN TestProAttach/error_on_'pro_attach'_error 574s === PAUSE TestProAttach/error_on_'pro_attach'_error 574s === CONT TestProStatus 574s === RUN TestProStatus/success_on_attached_distro 574s === PAUSE TestProStatus/success_on_attached_distro 574s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 574s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 574s === RUN TestProStatus/error_on_'pro_attach'_error 574s === PAUSE TestProStatus/error_on_'pro_attach'_error 574s === RUN TestProStatus/success_on_unattached_distro 574s === PAUSE TestProStatus/success_on_unattached_distro 574s === CONT TestUserProfileDir 574s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 574s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 574s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 574s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 574s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 574s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 574s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 574s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 574s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 574s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 574s === RUN TestUserProfileDir/Error_on_cmd.exe_error 574s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 574s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 574s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 574s === RUN TestUserProfileDir/Error_on_wslpath_error 574s === PAUSE TestUserProfileDir/Error_on_wslpath_error 574s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 574s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 574s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 574s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 574s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 574s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 574s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 574s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 574s === NAME TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 574s === NAME TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 574s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 574s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 574s === CONT TestInfo 574s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 574s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 574s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 574s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 574s === RUN TestInfo/Success 574s === PAUSE TestInfo/Success 574s === RUN TestInfo/Error_when_WslDistroName_fails 574s === PAUSE TestInfo/Error_when_WslDistroName_fails 574s === RUN TestInfo/Error_when_pro_status_command_fails 574s === PAUSE TestInfo/Error_when_pro_status_command_fails 574s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 574s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 574s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 574s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 574s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 574s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 574s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 574s --- PASS: TestNew (0.00s) 574s --- PASS: TestNew/Return_a_new_system (1.61s) 574s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (3.83s) 574s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 574s === CONT TestWslDistroName/Success_using_wslpath 574s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 574s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 574s --- PASS: TestLandscapeDisable (0.00s) 574s --- PASS: TestLandscapeDisable/Success (31.46s) 574s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (28.93s) 574s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 574s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 574s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 574s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 574s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 574s === CONT TestWindowsHostAddress/With_NAT 574s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 574s === CONT TestWindowsHostAddress/Without_NAT 574s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 574s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 574s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 574s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 574s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 574s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 574s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 574s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (5.95s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (2.14s) 574s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes (28.93s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (32.66s) 574s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (33.64s) 574s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (1.59s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.86s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (33.38s) 574s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (35.33s) 574s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (36.00s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (2.04s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (21.23s) 574s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (55.14s) 574s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (50.54s) 574s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (48.24s) 574s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (47.78s) 574s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (48.22s) 574s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (50.59s) 574s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 574s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 574s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 574s --- PASS: TestWslDistroName (0.00s) 574s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.21s) 574s --- PASS: TestWslDistroName/Success_using_wslpath (23.50s) 574s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (25.14s) 574s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (26.48s) 574s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 574s === CONT TestLandscapeEnable/Regular_with_additional_keys 574s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 574s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 574s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 574s --- PASS: TestWindowsHostAddress (0.00s) 574s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (20.59s) 574s --- PASS: TestWindowsHostAddress/With_NAT (22.43s) 574s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (20.58s) 574s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (21.37s) 574s --- PASS: TestWindowsHostAddress/Without_NAT (24.17s) 574s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (23.82s) 574s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 574s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 574s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 574s === NAME TestLandscapeEnable/Refresh_computer_title_if_changed 574s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 574s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 574s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 574s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 574s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 574s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 574s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 574s === NAME TestLandscapeEnable/Regular_with_additional_keys 574s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 574s === CONT TestProAttach/success 574s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 574s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 574s === CONT TestProStatus/success_on_attached_distro 574s === CONT TestProAttach/error_on_'pro_attach'_error 574s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 574s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 574s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 574s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 574s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 574s === CONT TestProStatus/success_on_unattached_distro 574s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 574s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 574s === CONT TestProStatus/error_on_'pro_attach'_error 574s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 574s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 574s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 574s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 574s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 574s --- PASS: TestLandscapeEnable (0.00s) 574s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (1.06s) 574s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.30s) 574s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (20.20s) 574s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (18.40s) 574s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (18.08s) 574s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (35.56s) 574s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (19.72s) 574s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (18.89s) 574s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (34.45s) 574s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (16.70s) 574s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (15.83s) 574s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (34.86s) 574s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (33.94s) 574s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (29.80s) 574s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (27.20s) 574s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (27.61s) 574s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 574s system_test.go:245: Removing default proc/mounts 574s === CONT TestUserProfileDir/Error_on_wslpath_error 574s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 574s --- PASS: TestProAttach (0.00s) 574s --- PASS: TestProAttach/success (13.13s) 574s --- PASS: TestProAttach/error_on_'pro_attach'_error (15.25s) 574s === CONT TestUserProfileDir/Error_on_cmd.exe_error 574s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 574s system_test.go:245: Removing default proc/mounts 574s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 574s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 574s --- PASS: TestProStatus (0.00s) 574s --- PASS: TestProStatus/success_on_attached_distro (13.31s) 574s --- PASS: TestProStatus/success_on_unattached_distro (11.96s) 574s --- PASS: TestProStatus/error_on_'pro_attach'_error (12.23s) 574s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (13.14s) 574s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 574s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 574s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 574s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 574s === CONT TestProDetach/success_on_attached_distro 574s === CONT TestProDetach/success_on_unattached_distro 574s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 574s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 574s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 574s === CONT TestInfo/Error_when_pro_status_command_fails 574s === CONT TestInfo/Error_when_WslDistroName_fails 574s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 574s --- PASS: TestUserProfileDir (0.00s) 574s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.16s) 574s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.53s) 574s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.53s) 574s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (11.03s) 574s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (23.94s) 574s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (10.62s) 574s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (21.38s) 574s --- PASS: TestUserProfileDir/Error_on_wslpath_error (21.09s) 574s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (19.89s) 574s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (18.23s) 574s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (19.03s) 574s === CONT TestInfo/Success 574s --- PASS: TestProDetach (0.00s) 574s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (8.86s) 574s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (9.84s) 574s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (10.29s) 574s --- PASS: TestProDetach/success_on_attached_distro (7.75s) 574s --- PASS: TestProDetach/success_on_unattached_distro (8.70s) 574s --- PASS: TestInfo (0.00s) 574s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (8.09s) 574s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (6.38s) 574s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (6.53s) 574s --- PASS: TestInfo/Error_when_pro_status_command_fails (5.83s) 574s --- PASS: TestInfo/Error_when_WslDistroName_fails (5.85s) 574s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (4.79s) 574s --- PASS: TestInfo/Success (3.20s) 574s PASS 574s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 185.731s 574s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 574s FAIL 574s autopkgtest [07:54:30]: test command1: -----------------------] 579s command1 FAIL non-zero exit status 1 579s autopkgtest [07:54:35]: test command1: - - - - - - - - - - results - - - - - - - - - - 583s autopkgtest [07:54:39]: @@@@@@@@@@@@@@@@@@@@ summary 583s command1 FAIL non-zero exit status 1