0s autopkgtest [09:11:55]: starting date and time: 2026-02-05 09:11:55+0000 0s autopkgtest [09:11:55]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [09:11:55]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.8px6r3kx/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc,src:chiark-tcl --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 chiark-tcl/1.3.7build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-31.secgroup --name adt-resolute-arm64-wsl-pro-service-20260205-091155-juju-7f2275-prod-proposed-migration-environment-20-c88def8f-c94d-43d9-8742-1bd366f84078 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 7s Creating nova instance adt-resolute-arm64-wsl-pro-service-20260205-091155-juju-7f2275-prod-proposed-migration-environment-20-c88def8f-c94d-43d9-8742-1bd366f84078 from image adt/ubuntu-resolute-arm64-server-20260204.img (UUID f58d981d-b271-4157-b9b1-fd704695563c)... 82s autopkgtest [09:13:17]: testbed dpkg architecture: arm64 82s autopkgtest [09:13:17]: testbed apt version: 3.1.14 83s autopkgtest [09:13:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 83s autopkgtest [09:13:18]: testbed release detected to be: None 84s autopkgtest [09:13:19]: updating testbed package index (apt update) 86s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 86s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 86s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 86s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 86s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [228 kB] 86s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 86s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 86s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 87s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [269 kB] 87s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7264 B] 87s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 87s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 87s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1556 kB] 87s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [41.9 kB] 87s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [26.0 kB] 87s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [792 B] 91s Fetched 4067 kB in 2s (1783 kB/s) 92s Reading package lists... 94s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 94s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 94s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 94s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 95s Reading package lists... 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade... 96s The following packages will be upgraded: 96s libc-bin libc-gconv-modules-extra libc6 locales pollinate python3-linkify-it 96s python3-referencing sed 96s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 8110 kB of archives. 96s After this operation, 0 B of additional disk space will be used. 96s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 sed arm64 4.9-2build3 [193 kB] 96s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-gconv-modules-extra arm64 2.42-2ubuntu5 [1413 kB] 97s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6 arm64 2.42-2ubuntu5 [1594 kB] 97s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-bin arm64 2.42-2ubuntu5 [599 kB] 97s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 locales all 2.42-2ubuntu5 [4255 kB] 97s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 97s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 pollinate all 4.33-4ubuntu5 [14.0 kB] 97s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 97s dpkg-preconfigure: unable to re-open stdin: No such file or directory 97s Fetched 8110 kB in 1s (7435 kB/s) 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../sed_4.9-2build3_arm64.deb ... 99s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 99s Setting up sed (4.9-2build3) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 99s Setting up libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc6_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc6:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 99s Setting up libc6:arm64 (2.42-2ubuntu5) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc-bin_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 99s Setting up libc-bin (2.42-2ubuntu5) ... 100s (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 ... 89360 files and directories currently installed.) 100s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 100s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 100s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 100s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 100s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 100s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 100s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 100s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 100s Setting up locales (2.42-2ubuntu5) ... 101s Generating locales (this might take a while)... 104s en_US.UTF-8... done 104s Generation complete. 104s Setting up pollinate (4.33-4ubuntu5) ... 115s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 115s Setting up python3-referencing (0.36.2-1ubuntu2) ... 115s Processing triggers for man-db (2.13.1-1) ... 116s Processing triggers for install-info (7.2-5) ... 116s Processing triggers for systemd (259-1ubuntu3) ... 118s autopkgtest [09:13:52]: upgrading testbed (apt dist-upgrade and autopurge) 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s Calculating upgrade... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s Solving dependencies... 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s autopkgtest [09:14:01]: rebooting testbed after setup commands that affected boot 193s autopkgtest [09:15:08]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 200s autopkgtest [09:15:15]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 206s Get:1 http://ftpmaster.internal/ubuntu resolute/main wsl-pro-service 0.1.18 (dsc) [2064 B] 206s Get:2 http://ftpmaster.internal/ubuntu resolute/main wsl-pro-service 0.1.18 (tar) [1790 kB] 206s gpgv: Signature made Tue Jun 10 19:06:57 2025 UTC 206s gpgv: using RSA key 92FE4F8B40CADC4ECCD60C1F5961FDBE9C619809 206s gpgv: Can't check signature: No public key 206s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.18.dsc: no acceptable signature found 206s autopkgtest [09:15:21]: testing package wsl-pro-service version 0.1.18 213s autopkgtest [09:15:28]: build not needed 225s autopkgtest [09:15:40]: test command1: preparing testbed 226s Reading package lists... 226s Building dependency tree... 226s Reading state information... 226s Solving dependencies... 226s The following NEW packages will be installed: 226s autoconf automake autopoint autotools-dev binutils-gold 226s binutils-gold-aarch64-linux-gnu build-essential cpp cpp-15 226s cpp-15-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper debugedit dh-apport 226s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 226s g++-15-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-15 226s gcc-15-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext golang-1.25-go 226s golang-1.25-src golang-go golang-src intltool-debian libarchive-zip-perl 226s libasan8 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl 226s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libhwasan0 libisl23 226s libitm1 liblsan0 libmpc3 libstdc++-15-dev libtool libtsan2 libubsan1 226s linux-libc-dev m4 po-debconf rpcsvc-proto 227s 0 upgraded, 55 newly installed, 0 to remove and 0 not upgraded. 227s Need to get 114 MB of archives. 227s After this operation, 472 MB of additional disk space will be used. 227s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 m4 arm64 1.4.20-2 [213 kB] 227s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 autoconf all 2.72-3.1ubuntu1 [384 kB] 227s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 autotools-dev all 20240727.1 [43.4 kB] 227s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 automake all 1:1.18.1-3build1 [582 kB] 228s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 autopoint all 0.23.2-1 [620 kB] 229s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold-aarch64-linux-gnu arm64 2.44-3 [2138 kB] 230s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold arm64 2.44-3 [2172 B] 230s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-dev-bin arm64 2.42-2ubuntu5 [22.5 kB] 230s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 linux-libc-dev arm64 6.18.0-9.9 [1834 kB] 231s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 libcrypt-dev arm64 1:4.5.1-1 [123 kB] 231s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 rpcsvc-proto arm64 1.4.3-1build1 [65.6 kB] 231s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6-dev arm64 2.42-2ubuntu5 [1765 kB] 232s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 libisl23 arm64 0.27-1build1 [676 kB] 232s Get:14 http://ftpmaster.internal/ubuntu resolute/main arm64 libmpc3 arm64 1.3.1-2 [55.6 kB] 232s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [11.7 MB] 233s Get:16 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15 arm64 15.2.0-12ubuntu1 [1030 B] 233s Get:17 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [5736 B] 233s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp arm64 4:15.2.0-4ubuntu1 [22.4 kB] 233s Get:19 http://ftpmaster.internal/ubuntu resolute/main arm64 libcc1-0 arm64 15.2.0-12ubuntu1 [49.0 kB] 233s Get:20 http://ftpmaster.internal/ubuntu resolute/main arm64 libgomp1 arm64 15.2.0-12ubuntu1 [147 kB] 233s Get:21 http://ftpmaster.internal/ubuntu resolute/main arm64 libitm1 arm64 15.2.0-12ubuntu1 [27.8 kB] 233s Get:22 http://ftpmaster.internal/ubuntu resolute/main arm64 libasan8 arm64 15.2.0-12ubuntu1 [2920 kB] 233s Get:23 http://ftpmaster.internal/ubuntu resolute/main arm64 liblsan0 arm64 15.2.0-12ubuntu1 [1316 kB] 233s Get:24 http://ftpmaster.internal/ubuntu resolute/main arm64 libtsan2 arm64 15.2.0-12ubuntu1 [2688 kB] 233s Get:25 http://ftpmaster.internal/ubuntu resolute/main arm64 libubsan1 arm64 15.2.0-12ubuntu1 [1175 kB] 233s Get:26 http://ftpmaster.internal/ubuntu resolute/main arm64 libhwasan0 arm64 15.2.0-12ubuntu1 [1638 kB] 233s Get:27 http://ftpmaster.internal/ubuntu resolute/main arm64 libgcc-15-dev arm64 15.2.0-12ubuntu1 [2600 kB] 233s Get:28 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [23.1 MB] 234s Get:29 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15 arm64 15.2.0-12ubuntu1 [519 kB] 234s Get:30 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [1206 B] 234s Get:31 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc arm64 4:15.2.0-4ubuntu1 [5016 B] 234s Get:32 http://ftpmaster.internal/ubuntu resolute/main arm64 libstdc++-15-dev arm64 15.2.0-12ubuntu1 [2549 kB] 234s Get:33 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [13.2 MB] 235s Get:34 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15 arm64 15.2.0-12ubuntu1 [25.3 kB] 235s Get:35 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [956 B] 235s Get:36 http://ftpmaster.internal/ubuntu resolute/main arm64 g++ arm64 4:15.2.0-4ubuntu1 [1080 B] 235s Get:37 http://ftpmaster.internal/ubuntu resolute/main arm64 build-essential arm64 12.12ubuntu2 [5254 B] 235s Get:38 http://ftpmaster.internal/ubuntu resolute/main arm64 libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 235s Get:39 http://ftpmaster.internal/ubuntu resolute/main arm64 libtool all 2.5.4-9 [169 kB] 235s Get:40 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-autoreconf all 21 [12.5 kB] 235s Get:41 http://ftpmaster.internal/ubuntu resolute/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 235s Get:42 http://ftpmaster.internal/ubuntu resolute/main arm64 libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 235s Get:43 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 235s Get:44 http://ftpmaster.internal/ubuntu resolute/main arm64 debugedit arm64 1:5.2-3build1 [47.9 kB] 235s Get:45 http://ftpmaster.internal/ubuntu resolute/main arm64 dwz arm64 0.16-2 [113 kB] 235s Get:46 http://ftpmaster.internal/ubuntu resolute/main arm64 gettext arm64 0.23.2-1 [998 kB] 235s Get:47 http://ftpmaster.internal/ubuntu resolute/main arm64 intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 235s Get:48 http://ftpmaster.internal/ubuntu resolute/main arm64 po-debconf all 1.0.22 [215 kB] 235s Get:49 http://ftpmaster.internal/ubuntu resolute/main arm64 debhelper all 13.28ubuntu1 [916 kB] 235s Get:50 http://ftpmaster.internal/ubuntu resolute/universe arm64 dh-apport all 2.33.1-0ubuntu3 [20.2 kB] 235s Get:51 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 236s Get:52 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-go arm64 1.25.0-0ubuntu1 [16.0 MB] 236s Get:53 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-src all 2:1.25~1 [5206 B] 236s Get:54 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-go arm64 2:1.25~1 [44.1 kB] 236s Get:55 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-golang all 1.63build1 [25.0 kB] 236s Fetched 114 MB in 10s (11.7 MB/s) 237s Selecting previously unselected package m4. 237s (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 ... 89360 files and directories currently installed.) 237s Preparing to unpack .../00-m4_1.4.20-2_arm64.deb ... 237s Unpacking m4 (1.4.20-2) ... 237s Selecting previously unselected package autoconf. 237s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 237s Unpacking autoconf (2.72-3.1ubuntu1) ... 237s Selecting previously unselected package autotools-dev. 237s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 237s Unpacking autotools-dev (20240727.1) ... 237s Selecting previously unselected package automake. 237s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ... 237s Unpacking automake (1:1.18.1-3build1) ... 237s Selecting previously unselected package autopoint. 237s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ... 237s Unpacking autopoint (0.23.2-1) ... 237s Selecting previously unselected package binutils-gold-aarch64-linux-gnu. 237s Preparing to unpack .../05-binutils-gold-aarch64-linux-gnu_2.44-3_arm64.deb ... 237s Unpacking binutils-gold-aarch64-linux-gnu (2.44-3) ... 237s Selecting previously unselected package binutils-gold. 237s Preparing to unpack .../06-binutils-gold_2.44-3_arm64.deb ... 237s Unpacking binutils-gold (2.44-3) ... 237s Selecting previously unselected package libc-dev-bin. 237s Preparing to unpack .../07-libc-dev-bin_2.42-2ubuntu5_arm64.deb ... 237s Unpacking libc-dev-bin (2.42-2ubuntu5) ... 237s Selecting previously unselected package linux-libc-dev:arm64. 237s Preparing to unpack .../08-linux-libc-dev_6.18.0-9.9_arm64.deb ... 237s Unpacking linux-libc-dev:arm64 (6.18.0-9.9) ... 237s Selecting previously unselected package libcrypt-dev:arm64. 237s Preparing to unpack .../09-libcrypt-dev_1%3a4.5.1-1_arm64.deb ... 237s Unpacking libcrypt-dev:arm64 (1:4.5.1-1) ... 237s Selecting previously unselected package rpcsvc-proto. 237s Preparing to unpack .../10-rpcsvc-proto_1.4.3-1build1_arm64.deb ... 237s Unpacking rpcsvc-proto (1.4.3-1build1) ... 237s Selecting previously unselected package libc6-dev:arm64. 237s Preparing to unpack .../11-libc6-dev_2.42-2ubuntu5_arm64.deb ... 237s Unpacking libc6-dev:arm64 (2.42-2ubuntu5) ... 237s Selecting previously unselected package libisl23:arm64. 237s Preparing to unpack .../12-libisl23_0.27-1build1_arm64.deb ... 237s Unpacking libisl23:arm64 (0.27-1build1) ... 237s Selecting previously unselected package libmpc3:arm64. 237s Preparing to unpack .../13-libmpc3_1.3.1-2_arm64.deb ... 237s Unpacking libmpc3:arm64 (1.3.1-2) ... 237s Selecting previously unselected package cpp-15-aarch64-linux-gnu. 237s Preparing to unpack .../14-cpp-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 237s Unpacking cpp-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package cpp-15. 238s Preparing to unpack .../15-cpp-15_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package cpp-aarch64-linux-gnu. 238s Preparing to unpack .../16-cpp-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 238s Unpacking cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 238s Selecting previously unselected package cpp. 238s Preparing to unpack .../17-cpp_4%3a15.2.0-4ubuntu1_arm64.deb ... 238s Unpacking cpp (4:15.2.0-4ubuntu1) ... 238s Selecting previously unselected package libcc1-0:arm64. 238s Preparing to unpack .../18-libcc1-0_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libcc1-0:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libgomp1:arm64. 238s Preparing to unpack .../19-libgomp1_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libgomp1:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libitm1:arm64. 238s Preparing to unpack .../20-libitm1_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libitm1:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libasan8:arm64. 238s Preparing to unpack .../21-libasan8_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libasan8:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package liblsan0:arm64. 238s Preparing to unpack .../22-liblsan0_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking liblsan0:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libtsan2:arm64. 238s Preparing to unpack .../23-libtsan2_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libtsan2:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libubsan1:arm64. 238s Preparing to unpack .../24-libubsan1_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libubsan1:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libhwasan0:arm64. 238s Preparing to unpack .../25-libhwasan0_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libhwasan0:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package libgcc-15-dev:arm64. 238s Preparing to unpack .../26-libgcc-15-dev_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking libgcc-15-dev:arm64 (15.2.0-12ubuntu1) ... 238s Selecting previously unselected package gcc-15-aarch64-linux-gnu. 238s Preparing to unpack .../27-gcc-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 238s Unpacking gcc-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package gcc-15. 239s Preparing to unpack .../28-gcc-15_15.2.0-12ubuntu1_arm64.deb ... 239s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package gcc-aarch64-linux-gnu. 239s Preparing to unpack .../29-gcc-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 239s Unpacking gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package gcc. 239s Preparing to unpack .../30-gcc_4%3a15.2.0-4ubuntu1_arm64.deb ... 239s Unpacking gcc (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package libstdc++-15-dev:arm64. 239s Preparing to unpack .../31-libstdc++-15-dev_15.2.0-12ubuntu1_arm64.deb ... 239s Unpacking libstdc++-15-dev:arm64 (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package g++-15-aarch64-linux-gnu. 239s Preparing to unpack .../32-g++-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 239s Unpacking g++-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package g++-15. 239s Preparing to unpack .../33-g++-15_15.2.0-12ubuntu1_arm64.deb ... 239s Unpacking g++-15 (15.2.0-12ubuntu1) ... 239s Selecting previously unselected package g++-aarch64-linux-gnu. 239s Preparing to unpack .../34-g++-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 239s Unpacking g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package g++. 239s Preparing to unpack .../35-g++_4%3a15.2.0-4ubuntu1_arm64.deb ... 239s Unpacking g++ (4:15.2.0-4ubuntu1) ... 239s Selecting previously unselected package build-essential. 239s Preparing to unpack .../36-build-essential_12.12ubuntu2_arm64.deb ... 239s Unpacking build-essential (12.12ubuntu2) ... 239s Selecting previously unselected package libdebhelper-perl. 239s Preparing to unpack .../37-libdebhelper-perl_13.28ubuntu1_all.deb ... 239s Unpacking libdebhelper-perl (13.28ubuntu1) ... 239s Selecting previously unselected package libtool. 239s Preparing to unpack .../38-libtool_2.5.4-9_all.deb ... 239s Unpacking libtool (2.5.4-9) ... 239s Selecting previously unselected package dh-autoreconf. 239s Preparing to unpack .../39-dh-autoreconf_21_all.deb ... 239s Unpacking dh-autoreconf (21) ... 239s Selecting previously unselected package libarchive-zip-perl. 239s Preparing to unpack .../40-libarchive-zip-perl_1.68-1_all.deb ... 239s Unpacking libarchive-zip-perl (1.68-1) ... 239s Selecting previously unselected package libfile-stripnondeterminism-perl. 239s Preparing to unpack .../41-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 239s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 239s Selecting previously unselected package dh-strip-nondeterminism. 239s Preparing to unpack .../42-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 239s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 240s Selecting previously unselected package debugedit. 240s Preparing to unpack .../43-debugedit_1%3a5.2-3build1_arm64.deb ... 240s Unpacking debugedit (1:5.2-3build1) ... 240s Selecting previously unselected package dwz. 240s Preparing to unpack .../44-dwz_0.16-2_arm64.deb ... 240s Unpacking dwz (0.16-2) ... 240s Selecting previously unselected package gettext. 240s Preparing to unpack .../45-gettext_0.23.2-1_arm64.deb ... 240s Unpacking gettext (0.23.2-1) ... 240s Selecting previously unselected package intltool-debian. 240s Preparing to unpack .../46-intltool-debian_0.35.0+20060710.6build1_all.deb ... 240s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 240s Selecting previously unselected package po-debconf. 240s Preparing to unpack .../47-po-debconf_1.0.22_all.deb ... 240s Unpacking po-debconf (1.0.22) ... 240s Selecting previously unselected package debhelper. 240s Preparing to unpack .../48-debhelper_13.28ubuntu1_all.deb ... 240s Unpacking debhelper (13.28ubuntu1) ... 240s Selecting previously unselected package dh-apport. 240s Preparing to unpack .../49-dh-apport_2.33.1-0ubuntu3_all.deb ... 240s Unpacking dh-apport (2.33.1-0ubuntu3) ... 240s Selecting previously unselected package golang-1.25-src. 240s Preparing to unpack .../50-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 240s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 241s Selecting previously unselected package golang-1.25-go. 241s Preparing to unpack .../51-golang-1.25-go_1.25.0-0ubuntu1_arm64.deb ... 241s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 241s Selecting previously unselected package golang-src. 241s Preparing to unpack .../52-golang-src_2%3a1.25~1_all.deb ... 241s Unpacking golang-src (2:1.25~1) ... 241s Selecting previously unselected package golang-go:arm64. 242s Preparing to unpack .../53-golang-go_2%3a1.25~1_arm64.deb ... 242s Unpacking golang-go:arm64 (2:1.25~1) ... 242s Selecting previously unselected package dh-golang. 242s Preparing to unpack .../54-dh-golang_1.63build1_all.deb ... 242s Unpacking dh-golang (1.63build1) ... 242s Setting up libarchive-zip-perl (1.68-1) ... 242s Setting up libdebhelper-perl (13.28ubuntu1) ... 242s Setting up linux-libc-dev:arm64 (6.18.0-9.9) ... 242s Setting up m4 (1.4.20-2) ... 242s Setting up binutils-gold-aarch64-linux-gnu (2.44-3) ... 242s Setting up libgomp1:arm64 (15.2.0-12ubuntu1) ... 242s Setting up dh-apport (2.33.1-0ubuntu3) ... 242s Setting up autotools-dev (20240727.1) ... 242s Setting up rpcsvc-proto (1.4.3-1build1) ... 242s Setting up libmpc3:arm64 (1.3.1-2) ... 242s Setting up autopoint (0.23.2-1) ... 242s Setting up autoconf (2.72-3.1ubuntu1) ... 242s Setting up libubsan1:arm64 (15.2.0-12ubuntu1) ... 242s Setting up dwz (0.16-2) ... 242s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 242s Setting up libhwasan0:arm64 (15.2.0-12ubuntu1) ... 242s Setting up libcrypt-dev:arm64 (1:4.5.1-1) ... 242s Setting up libasan8:arm64 (15.2.0-12ubuntu1) ... 242s Setting up debugedit (1:5.2-3build1) ... 242s Setting up libtsan2:arm64 (15.2.0-12ubuntu1) ... 242s Setting up libisl23:arm64 (0.27-1build1) ... 242s Setting up libc-dev-bin (2.42-2ubuntu5) ... 242s Setting up golang-src (2:1.25~1) ... 242s Setting up libcc1-0:arm64 (15.2.0-12ubuntu1) ... 242s Setting up liblsan0:arm64 (15.2.0-12ubuntu1) ... 242s Setting up libitm1:arm64 (15.2.0-12ubuntu1) ... 242s Setting up automake (1:1.18.1-3build1) ... 242s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 242s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 242s Setting up cpp-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 242s Setting up gettext (0.23.2-1) ... 242s Setting up libgcc-15-dev:arm64 (15.2.0-12ubuntu1) ... 242s Setting up binutils-gold (2.44-3) ... 242s Setting up intltool-debian (0.35.0+20060710.6build1) ... 242s Setting up cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 242s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 242s Setting up cpp-15 (15.2.0-12ubuntu1) ... 242s Setting up cpp (4:15.2.0-4ubuntu1) ... 242s Setting up libc6-dev:arm64 (2.42-2ubuntu5) ... 242s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 242s Setting up gcc-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 242s Setting up golang-go:arm64 (2:1.25~1) ... 242s Setting up po-debconf (1.0.22) ... 242s Setting up gcc-15 (15.2.0-12ubuntu1) ... 242s Setting up libstdc++-15-dev:arm64 (15.2.0-12ubuntu1) ... 242s Setting up g++-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 242s Setting up g++-15 (15.2.0-12ubuntu1) ... 242s Setting up gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 242s Setting up libtool (2.5.4-9) ... 242s Setting up gcc (4:15.2.0-4ubuntu1) ... 242s Setting up dh-autoreconf (21) ... 242s Setting up g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 242s Setting up debhelper (13.28ubuntu1) ... 242s Setting up g++ (4:15.2.0-4ubuntu1) ... 242s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 242s Setting up build-essential (12.12ubuntu2) ... 242s Setting up dh-golang (1.63build1) ... 242s Processing triggers for man-db (2.13.1-1) ... 243s Processing triggers for install-info (7.2-5) ... 243s Processing triggers for libc-bin (2.42-2ubuntu5) ... 255s autopkgtest [09:16:10]: test command1: ./debian/tests/test 255s autopkgtest [09:16:10]: test command1: [----------------------- 298s === RUN TestRunSignal 298s === RUN TestRunSignal/Send_SIGINT_exits 298s INFO Starting WSL Pro Service version Dev 298s === RUN TestRunSignal/Send_SIGTERM_exits 298s INFO Starting WSL Pro Service version Dev 298s --- PASS: TestRunSignal (0.20s) 298s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 298s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 298s === RUN TestRun 298s === PAUSE TestRun 298s === CONT TestRun 298s === RUN TestRun/Run_and_usage_error_only_does_not_fail 298s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 298s === RUN TestRun/Run_and_exit_successfully 298s === PAUSE TestRun/Run_and_exit_successfully 298s === RUN TestRun/Run_and_return_error 298s === PAUSE TestRun/Run_and_return_error 298s === RUN TestRun/Run_and_return_usage_error 298s === PAUSE TestRun/Run_and_return_usage_error 298s === CONT TestRun/Run_and_usage_error_only_does_not_fail 298s INFO Starting WSL Pro Service version Dev 298s === CONT TestRun/Run_and_return_error 298s INFO Starting WSL Pro Service version Dev 298s ERROR context.Background Error requested 298s === CONT TestRun/Run_and_exit_successfully 298s INFO Starting WSL Pro Service version Dev 298s === CONT TestRun/Run_and_return_usage_error 298s INFO Starting WSL Pro Service version Dev 298s ERROR context.Background Error requested 298s --- PASS: TestRun (0.00s) 298s --- PASS: TestRun/Run_and_return_error (0.10s) 298s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 298s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 298s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 298s PASS 298s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.431s 312s === RUN TestHelp 312s --- PASS: TestHelp (0.00s) 312s === RUN TestCompletion 312s --- PASS: TestCompletion (0.00s) 312s === RUN TestVersion 312s --- PASS: TestVersion (0.00s) 312s === RUN TestConfigBadArg 312s --- PASS: TestConfigBadArg (0.00s) 312s === RUN TestConfigArg 312s --- PASS: TestConfigArg (0.00s) 312s === RUN TestConfigAutoDetect 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 312s --- PASS: TestConfigAutoDetect (0.00s) 312s === RUN TestNoUsageError 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 312s --- PASS: TestNoUsageError (0.00s) 312s === RUN TestUsageError 312s === PAUSE TestUsageError 312s === RUN TestCanQuitWhenExecute 312s === PAUSE TestCanQuitWhenExecute 312s === RUN TestCanQuitTwice 312s === PAUSE TestCanQuitTwice 312s === RUN TestAppCanQuitWithoutExecute 312s === PAUSE TestAppCanQuitWithoutExecute 312s === RUN TestAppRunFailsOnComponentsCreationAndQuit 312s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 312s === RUN TestAppGetRootCmd 312s === PAUSE TestAppGetRootCmd 312s === RUN TestWithProMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithProMock (0.00s) 312s === RUN TestWithWslPathMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithWslPathMock (0.00s) 312s === RUN TestWithWslInfoMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithWslInfoMock (0.00s) 312s === RUN TestWithCmdExeMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithCmdExeMock (0.00s) 312s === CONT TestUsageError 312s === CONT TestAppCanQuitWithoutExecute 312s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 312s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 312s === CONT TestAppGetRootCmd 312s --- PASS: TestAppGetRootCmd (0.00s) 312s === CONT TestAppRunFailsOnComponentsCreationAndQuit 312s --- PASS: TestUsageError (0.00s) 312s === CONT TestCanQuitTwice 312s time="2026-02-05T09:16:53Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37057" 312s time="2026-02-05T09:16:53Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3545364629/b385/wsl-pro-service.yaml" 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:74 New() Building new daemon" 312s time="2026-02-05T09:16:53Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3545364629/b385/wsl-pro-service.yaml" 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 312s time="2026-02-05T09:16:53Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:74 New() Building new daemon" 312s time="2026-02-05T09:16:58Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 312s time="2026-02-05T09:16:58Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 312s time="2026-02-05T09:16:58Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5804" 312s time="2026-02-05T09:16:58Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 312s time="2026-02-05T09:16:58Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 312s time="2026-02-05T09:16:58Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5804" 312s time="2026-02-05T09:16:58Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:16:58Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:01Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s === CONT TestCanQuitWhenExecute 312s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (7.82s) 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40879" 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3545364629/b385/wsl-pro-service.yaml" 312s time="2026-02-05T09:17:01Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 312s time="2026-02-05T09:17:01Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:74 New() Building new daemon" 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:46425" 312s time="2026-02-05T09:17:01Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 312s time="2026-02-05T09:17:01Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 312s time="2026-02-05T09:17:01Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 312s time="2026-02-05T09:17:04Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 312s time="2026-02-05T09:17:04Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.19a7hH/build.cf6/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 312s time="2026-02-05T09:17:04Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.19a7hH/build.cf6/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 312s time="2026-02-05T09:17:04Z" level=warning msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:196 (*Daemon).serveOnce() Daemon: disconnected from Windows host" 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 312s time="2026-02-05T09:17:04Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:04Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:17:04Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:04Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s time="2026-02-05T09:17:04Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.19a7hH/build.cf6/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 312s --- PASS: TestCanQuitTwice (10.38s) 312s time="2026-02-05T09:17:05Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 312s time="2026-02-05T09:17:05Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 312s time="2026-02-05T09:17:05Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5804" 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:40879" 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 312s time="2026-02-05T09:17:06Z" level=warning msg="/tmp/autopkgtest.19a7hH/build.cf6/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:40879: connect: connection refused\"" 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 312s time="2026-02-05T09:17:06Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 312s time="2026-02-05T09:17:06Z" level=info msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 312s time="2026-02-05T09:17:06Z" level=debug msg="/tmp/autopkgtest.19a7hH/build.cf6/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 312s --- PASS: TestCanQuitWhenExecute (5.24s) 312s PASS 312s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 13.095s 312s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 312s === RUN TestApplyProToken 312s === PAUSE TestApplyProToken 312s === RUN TestApplyLandscapeConfig 312s === PAUSE TestApplyLandscapeConfig 312s === RUN TestWithProMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithProMock (0.00s) 312s === RUN TestWithLandscapeConfigMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithLandscapeConfigMock (0.00s) 312s === RUN TestWithWslPathMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithWslPathMock (0.00s) 312s === RUN TestWithWslInfoMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithWslInfoMock (0.00s) 312s === RUN TestWithCmdExeMock 312s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 312s --- SKIP: TestWithCmdExeMock (0.00s) 312s === CONT TestApplyProToken 312s === CONT TestApplyLandscapeConfig 312s === RUN TestApplyProToken/Error_calling_pro_detach 312s === PAUSE TestApplyProToken/Error_calling_pro_detach 312s === RUN TestApplyProToken/Error_calling_pro_attach 312s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 312s === PAUSE TestApplyProToken/Error_calling_pro_attach 312s === RUN TestApplyProToken/Success_attaching 312s === PAUSE TestApplyProToken/Success_attaching 312s === RUN TestApplyProToken/Success_detaching 312s === PAUSE TestApplyProToken/Success_detaching 312s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 312s === CONT TestApplyProToken/Error_calling_pro_detach 312s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 312s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 312s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 312s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 312s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 312s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 312s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 312s time="2026-02-05T09:16:55Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 312s time="2026-02-05T09:16:55Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 312s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 312s time="2026-02-05T09:16:57Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 312s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 312s time="2026-02-05T09:16:57Z" level=info msg="ApplyLandscapeConfig: received config: registering" 312s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 312s time="2026-02-05T09:16:59Z" level=info msg="ApplyLandscapeConfig: received config: registering" 312s === CONT TestApplyProToken/Success_attaching 312s time="2026-02-05T09:17:00Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 312s --- PASS: TestApplyLandscapeConfig (0.00s) 312s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (2.52s) 312s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (2.33s) 312s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (2.40s) 312s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (2.17s) 312s === CONT TestApplyProToken/Success_detaching 312s time="2026-02-05T09:17:01Z" level=info msg="ApplyProToken: Received empty token: detaching" 312s === CONT TestApplyProToken/Error_calling_pro_attach 312s time="2026-02-05T09:17:04Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 312s --- PASS: TestApplyProToken (0.00s) 312s --- PASS: TestApplyProToken/Error_calling_pro_detach (2.03s) 312s --- PASS: TestApplyProToken/Success_detaching (2.35s) 312s --- PASS: TestApplyProToken/Success_attaching (4.32s) 312s --- PASS: TestApplyProToken/Error_calling_pro_attach (2.40s) 312s PASS 312s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 11.331s 312s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 452s === RUN TestNew 452s === PAUSE TestNew 452s === RUN TestServe 452s === PAUSE TestServe 452s === RUN TestServeAndQuit 452s === PAUSE TestServeAndQuit 452s === RUN TestRetryLogic 452s === PAUSE TestRetryLogic 452s === RUN TestReconnection 452s === PAUSE TestReconnection 452s === RUN TestWithProMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithProMock (0.00s) 452s === RUN TestWithWslPathMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslPathMock (0.00s) 452s === RUN TestWithWslInfoMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslInfoMock (0.00s) 452s === RUN TestWithCmdExeMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithCmdExeMock (0.00s) 452s === CONT TestNew 452s === CONT TestRetryLogic 452s === RUN TestRetryLogic/When_max_attempts_are_exhausted 452s === PAUSE TestRetryLogic/When_max_attempts_are_exhausted 452s === RUN TestNew/Success 452s === PAUSE TestNew/Success 452s === RUN TestNew/Error_when_WslPath_returns_error 452s === PAUSE TestNew/Error_when_WslPath_returns_error 452s === RUN TestRetryLogic/Error_only_when_action_errors 452s === PAUSE TestRetryLogic/Error_only_when_action_errors 452s === RUN TestRetryLogic/Without_retries 452s === PAUSE TestRetryLogic/Without_retries 452s === RUN TestRetryLogic/With_the_context_pre-cancelled 452s === PAUSE TestRetryLogic/With_the_context_pre-cancelled 452s === CONT TestServeAndQuit 452s === RUN TestRetryLogic/With_the_context_cancelled_while_waiting 452s === RUN TestServeAndQuit/Success_with_graceful_quit 452s === PAUSE TestServeAndQuit/Success_with_graceful_quit 452s === PAUSE TestRetryLogic/With_the_context_cancelled_while_waiting 452s === RUN TestServeAndQuit/Success_with_forceful_quit 452s === CONT TestServe 452s === PAUSE TestServeAndQuit/Success_with_forceful_quit 452s === RUN TestServe/Success_with_a_broken_Landscape_config 452s === PAUSE TestServe/Success_with_a_broken_Landscape_config 452s === RUN TestServe/No_connection_because_the_port_file_is_empty 452s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 452s === RUN TestServe/No_connection_because_the_port_file_has_port_0 452s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 452s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 452s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 452s === RUN TestServeAndQuit/Success_with_double_quit 452s === RUN TestServe/No_connection_because_there_are_no_certificates 452s === PAUSE TestServeAndQuit/Success_with_double_quit 452s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 452s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 452s === CONT TestReconnection 452s === RUN TestReconnection/Success_connecting_after_failing_to_connect 452s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 452s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 452s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 452s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 452s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 452s === CONT TestNew/Success 452s === PAUSE TestServe/No_connection_because_there_are_no_certificates 452s === RUN TestServe/Success 452s === PAUSE TestServe/Success 452s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 452s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 452s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 452s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 452s === RUN TestServe/No_connection_because_there_is_no_server 452s === PAUSE TestServe/No_connection_because_there_is_no_server 452s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 452s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 452s === RUN TestServe/Error_because_the_context_is_pre-cancelled 452s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 452s === RUN TestServe/Error_because_the_notifier_returns_an_error 452s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 452s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 452s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 452s === RUN TestServe/Success_with_systemd_notifier_returning_true 452s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 452s === CONT TestRetryLogic/When_max_attempts_are_exhausted 452s time="2026-02-05T09:17:07Z" level=debug msg="Building new daemon" 452s === CONT TestNew/Error_when_WslPath_returns_error 452s time="2026-02-05T09:17:07Z" level=debug msg="Building new daemon" 452s === CONT TestRetryLogic/With_the_context_cancelled_while_waiting 452s === CONT TestRetryLogic/With_the_context_pre-cancelled 452s === CONT TestRetryLogic/Without_retries 452s === CONT TestRetryLogic/Error_only_when_action_errors 452s === CONT TestServeAndQuit/Success_with_graceful_quit 452s --- PASS: TestNew (0.00s) 452s --- PASS: TestNew/Success (3.37s) 452s --- PASS: TestNew/Error_when_WslPath_returns_error (3.31s) 452s time="2026-02-05T09:17:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40943" 452s time="2026-02-05T09:17:11Z" level=debug msg="Building new daemon" 452s --- PASS: TestRetryLogic (0.00s) 452s --- PASS: TestRetryLogic/When_max_attempts_are_exhausted (0.42s) 452s --- PASS: TestRetryLogic/With_the_context_cancelled_while_waiting (0.03s) 452s --- PASS: TestRetryLogic/With_the_context_pre-cancelled (0.00s) 452s --- PASS: TestRetryLogic/Error_only_when_action_errors (0.00s) 452s --- PASS: TestRetryLogic/Without_retries (0.71s) 452s === CONT TestReconnection/Success_connecting_after_failing_to_connect 452s time="2026-02-05T09:17:11Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:15Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:17:15Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:15Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:15Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:15Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:17:15Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:15Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:15Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:15Z" 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_connect917483207/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect917483207/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 452s time="2026-02-05T09:17:15Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:15Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38981" 452s time="2026-02-05T09:17:16Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40943" 452s time="2026-02-05T09:17:16Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:16Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:17:16Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:16Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:16Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:16Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:18Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38981" 452s time="2026-02-05T09:17:18Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:18Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:17:18Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:18Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:18Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:18Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:18Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:18Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:18Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:18Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:18Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:17:18Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:18Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:18Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:18Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40401" 452s time="2026-02-05T09:17:18Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:19Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:19Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:19Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:19Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:19Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:19Z" level=info msg="Stopping active requests." 452s time="2026-02-05T09:17:19Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:19Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:19Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:19Z" level=info msg="Reconnecting to Windows host in 2 seconds" 452s time="2026-02-05T09:17:19Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:19Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:17:19Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:19Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s === CONT TestServeAndQuit/Success_with_double_quit 452s time="2026-02-05T09:17:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40767" 452s time="2026-02-05T09:17:19Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:20Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:20Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:20Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:20Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:20Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s === CONT TestServeAndQuit/Success_with_forceful_quit 452s time="2026-02-05T09:17:20Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39259" 452s time="2026-02-05T09:17:20Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:22Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:17:22Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:22Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:22Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:22Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:23Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:17:23Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:23Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:23Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:23Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:23Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40767" 452s time="2026-02-05T09:17:23Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:23Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:17:23Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:23Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:24Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39259" 452s time="2026-02-05T09:17:24Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:24Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:17:24Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:24Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:24Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:24Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:24Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:24Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:24Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:24Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:24Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:24Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:24Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:24Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:24Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:24Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:24Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:17:24Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:25Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:25Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:25Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/Success_with_a_broken_Landscape_config 452s time="2026-02-05T09:17:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38683" 452s time="2026-02-05T09:17:25Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:25Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:25Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:25Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:25Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:25Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:25Z" level=info msg="Stopping active requests." 452s time="2026-02-05T09:17:25Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:25Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:25Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:25Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:17:25Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:25Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:25Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:25Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s --- PASS: TestServeAndQuit (0.00s) 452s --- PASS: TestServeAndQuit/Success_with_graceful_quit (7.50s) 452s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.12s) 452s --- PASS: TestServeAndQuit/Success_with_double_quit (5.11s) 452s --- PASS: TestServeAndQuit/Success_with_forceful_quit (5.12s) 452s === CONT TestServe/Success_with_systemd_notifier_returning_true 452s time="2026-02-05T09:17:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36973" 452s time="2026-02-05T09:17:25Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:27Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:27Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: read /tmp/TestServeSuccess_with_a_broken_Landscape_config2981296938/001/etc/landscape/client.conf: is a directory" 452s time="2026-02-05T09:17:27Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:28Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:17:28Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:28Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:28Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:28Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:17:28Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38683" 452s time="2026-02-05T09:17:28Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:28Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:28Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:29Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36973" 452s time="2026-02-05T09:17:29Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:29Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:17:29Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:17:29Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:29Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:29Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:29Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:29Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:31Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:17:31Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:31Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:31Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:31Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:31Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:31Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:31Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:31Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:31Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:31Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:31Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 452s time="2026-02-05T09:17:31Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38675" 452s time="2026-02-05T09:17:31Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:32Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:32Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:32Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:17:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:32Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:17:32Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:17:32Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:17:32Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:32Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:32Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s === CONT TestServe/Error_because_the_notifier_returns_an_error 452s time="2026-02-05T09:17:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41423" 452s time="2026-02-05T09:17:32Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:36Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:36Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:36Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:37Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 452s time="2026-02-05T09:17:37Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:37Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:37Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/Error_because_the_context_is_pre-cancelled 452s time="2026-02-05T09:17:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36095" 452s time="2026-02-05T09:17:37Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:38Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:38Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:38Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 452s time="2026-02-05T09:17:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44551" 452s time="2026-02-05T09:17:38Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:42Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:42Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:42Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_there_is_no_server 452s time="2026-02-05T09:17:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35153" 452s time="2026-02-05T09:17:42Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:42Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:42Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:42Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:45Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44551" 452s time="2026-02-05T09:17:45Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_3489791535/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 452s time="2026-02-05T09:17:45Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:45Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:45Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:45Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 452s time="2026-02-05T09:17:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43537" 452s time="2026-02-05T09:17:45Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:47Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:47Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:47Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:50Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35153" 452s time="2026-02-05T09:17:50Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:50Z" 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:35153: connect: connection refused\"" 452s time="2026-02-05T09:17:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:50Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:50Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:50Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 452s time="2026-02-05T09:17:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38059" 452s time="2026-02-05T09:17:50Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:50Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:50Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:50Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:50Z" 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" 452s time="2026-02-05T09:17:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:51Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:51Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:51Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/Success 452s time="2026-02-05T09:17:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34235" 452s time="2026-02-05T09:17:51Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:55Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:55Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:55Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:55Z" 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_exist2274805838/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2274805838/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 452s time="2026-02-05T09:17:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:17:56Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:17:56Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:17:56Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_there_are_no_certificates 452s time="2026-02-05T09:17:56Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42383" 452s time="2026-02-05T09:17:56Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:17:56Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:17:56Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:17:56Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:17:58Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34235" 452s time="2026-02-05T09:17:58Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:17:58Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:17:58Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:18:01Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:01Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:01Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:01Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:18:01Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:18:01Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:18:01Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:18:03Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42383" 452s time="2026-02-05T09:18:03Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates3638829445/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 452s time="2026-02-05T09:18:03Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:03Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:03Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:18:03Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:18:03Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:03Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:18:03Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 452s time="2026-02-05T09:18:03Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:03Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46791" 452s time="2026-02-05T09:18:03Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:18:03Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:03Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:18:03Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_the_port_file_has_port_0 452s time="2026-02-05T09:18:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39589" 452s time="2026-02-05T09:18:03Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:18:08Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:08Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:08Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:08Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:08Z" 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" 452s time="2026-02-05T09:18:08Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:08Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:08Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:08Z" 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" 452s time="2026-02-05T09:18:08Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:09Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:09Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:18:09Z" level=debug msg="All connections have now ended." 452s === CONT TestServe/No_connection_because_the_port_file_is_empty 452s time="2026-02-05T09:18:09Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:09Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:18:09Z" level=debug msg="All connections have now ended." 452s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 452s time="2026-02-05T09:18:09Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:18:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45275" 452s time="2026-02-05T09:18:09Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:18:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44413" 452s time="2026-02-05T09:18:14Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:18:14Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:14Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:14Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:14Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:18:14Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:14Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:14Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:14Z" 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" 452s time="2026-02-05T09:18:14Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:15Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:15Z" level=info msg="Waiting for active requests to close." 452s time="2026-02-05T09:18:15Z" level=debug msg="All connections have now ended." 452s --- PASS: TestServe (0.00s) 452s --- PASS: TestServe/Success_with_a_broken_Landscape_config (6.12s) 452s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (6.43s) 452s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (5.09s) 452s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (7.42s) 452s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (5.07s) 452s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (7.35s) 452s --- PASS: TestServe/No_connection_because_there_is_no_server (7.92s) 452s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (5.63s) 452s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (5.76s) 452s --- PASS: TestServe/Success (11.82s) 452s --- PASS: TestServe/No_connection_because_there_are_no_certificates (7.22s) 452s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (5.93s) 452s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (5.97s) 452s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (6.07s) 452s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 452s time="2026-02-05T09:18:15Z" level=debug msg="Building new daemon" 452s time="2026-02-05T09:18:16Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44413" 452s time="2026-02-05T09:18:16Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:18:16Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:18:16Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:18:16Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:18:19Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:18:19Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:18:19Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:18:19Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:18:20Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39367" 452s time="2026-02-05T09:18:20Z" level=debug msg="Ready state sent to systemd" 452s time="2026-02-05T09:18:20Z" level=debug msg="Checking cloud-init status" 452s time="2026-02-05T09:18:20Z" level=debug msg="No Landscape configuration detected: nothing to do" 452s time="2026-02-05T09:18:20Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:20Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:18:21Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39367" 452s time="2026-02-05T09:18:21Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:18:21Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:18:21Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:18:21Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:18:23Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:18:23Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:18:23Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:18:23Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:18:24Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:24Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 452s time="2026-02-05T09:18:24Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:18:24Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:18:24Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:24Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33625" 452s time="2026-02-05T09:18:25Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:18:25Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:18:26Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33625" 452s time="2026-02-05T09:18:26Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:18:26Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:18:26Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:18:26Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:18:28Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:18:28Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:18:28Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:18:28Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:18:29Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:29Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:18:29Z" level=info msg="Stopping active requests." 452s time="2026-02-05T09:18:29Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:29Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:18:29Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:18:29Z" level=info msg="Reconnecting to Windows host in 2 seconds" 452s time="2026-02-05T09:18:29Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:18:29Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:18:29Z" level=debug msg="All connections have now ended." 452s time="2026-02-05T09:19:24Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 452s time="2026-02-05T09:19:24Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:19:24Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:19:24Z" 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_co2538013040/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_co2538013040/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 452s time="2026-02-05T09:19:24Z" level=info msg="Reconnecting to Windows host in 1 seconds" 452s time="2026-02-05T09:19:24Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:19:24Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:24Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:24Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41901" 452s time="2026-02-05T09:19:25Z" level=info msg="Daemon: connecting to Windows Agent from PID 6874" 452s time="2026-02-05T09:19:25Z" level=debug msg="Updated systemd status to \"Connecting\"" 452s time="2026-02-05T09:19:26Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:41901" 452s time="2026-02-05T09:19:26Z" level=info msg="Daemon: completed connection to Windows Agent" 452s time="2026-02-05T09:19:26Z" level=debug msg="Updated systemd status to \"Connected\"" 452s time="2026-02-05T09:19:26Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 452s time="2026-02-05T09:19:26Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 452s time="2026-02-05T09:19:26Z" level=debug msg="Server: sent preface messages to all streams" 452s time="2026-02-05T09:19:26Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:19:26Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:19:26Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:19:27Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:27Z" level=info msg="Stopping daemon requested." 452s time="2026-02-05T09:19:27Z" level=info msg="Stopping active requests." 452s time="2026-02-05T09:19:27Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:27Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:19:27Z" level=warning msg="Daemon: disconnected from Windows host" 452s time="2026-02-05T09:19:27Z" level=info msg="Reconnecting to Windows host in 2 seconds" 452s time="2026-02-05T09:19:27Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 452s time="2026-02-05T09:19:27Z" level=debug msg="Updated systemd status to \"Stopped\"" 452s time="2026-02-05T09:19:27Z" level=debug msg="All connections have now ended." 452s --- PASS: TestReconnection (0.00s) 452s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (8.38s) 452s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (13.77s) 452s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (78.02s) 452s PASS 452s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 139.950s 452s === RUN TestConnect 452s === PAUSE TestConnect 452s === RUN TestSendAndRecv 452s --- PASS: TestSendAndRecv (0.40s) 452s === RUN TestServe 452s === PAUSE TestServe 452s === RUN TestStop 452s === PAUSE TestStop 452s === RUN TestWithProMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithProMock (0.00s) 452s === RUN TestWithWslPathMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslPathMock (0.00s) 452s === RUN TestWithWslInfoMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslInfoMock (0.00s) 452s === RUN TestWithCmdExeMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithCmdExeMock (0.00s) 452s === CONT TestConnect 452s === RUN TestConnect/Success 452s === PAUSE TestConnect/Success 452s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 452s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 452s === CONT TestConnect/Success 452s === CONT TestServe 452s time="2026-02-05T09:17:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33805" 452s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 452s time="2026-02-05T09:17:10Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:10Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:10Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:16Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:16Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:16Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s --- PASS: TestServe (7.95s) 452s === CONT TestStop 452s time="2026-02-05T09:17:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36289" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: Connected ready" 452s time="2026-02-05T09:17:18Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 452s time="2026-02-05T09:17:28Z" level=warning msg="Streamserver: could not gather info after command completion: could not obtain pro status: pro status: /usr/bin/bash: error: context canceled.\n Stdout: \n Stderr: " 452s time="2026-02-05T09:17:28Z" level=warning msg="Streamserver: could not stream back info after command completion: EOF" 452s time="2026-02-05T09:17:28Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:28Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 452s time="2026-02-05T09:17:28Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 452s --- PASS: TestStop (12.02s) 452s --- PASS: TestConnect (0.00s) 452s --- PASS: TestConnect/Success (0.31s) 452s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.00s) 452s PASS 452s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 20.754s 452s === RUN TestNew 452s === PAUSE TestNew 452s === RUN TestInfo 452s === PAUSE TestInfo 452s === RUN TestWslDistroName 452s === PAUSE TestWslDistroName 452s === RUN TestUserProfileDir 452s === PAUSE TestUserProfileDir 452s === RUN TestProStatus 452s === PAUSE TestProStatus 452s === RUN TestProAttach 452s === PAUSE TestProAttach 452s === RUN TestProDetach 452s === PAUSE TestProDetach 452s === RUN TestLandscapeEnable 452s === PAUSE TestLandscapeEnable 452s === RUN TestWindowsHostAddress 452s === PAUSE TestWindowsHostAddress 452s === RUN TestLandscapeDisable 452s === PAUSE TestLandscapeDisable 452s === RUN TestEnsureValidLandscapeConfig 452s === PAUSE TestEnsureValidLandscapeConfig 452s === RUN TestRealBackend 452s === PAUSE TestRealBackend 452s === RUN TestWithProMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithProMock (0.00s) 452s === RUN TestWithLandscapeConfigMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithLandscapeConfigMock (0.00s) 452s === RUN TestWithWslPathMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslPathMock (0.00s) 452s === RUN TestWithWslInfoMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithWslInfoMock (0.00s) 452s === RUN TestWithCmdExeMock 452s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 452s --- SKIP: TestWithCmdExeMock (0.00s) 452s === CONT TestNew 452s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 452s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 452s === RUN TestNew/Return_a_new_system 452s === PAUSE TestNew/Return_a_new_system 452s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 452s === CONT TestProDetach 452s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 452s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 452s === RUN TestProDetach/success_on_unattached_distro 452s === PAUSE TestProDetach/success_on_unattached_distro 452s === RUN TestProDetach/success_on_attached_distro 452s === PAUSE TestProDetach/success_on_attached_distro 452s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 452s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 452s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 452s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 452s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 452s === CONT TestProAttach 452s === RUN TestProAttach/success 452s === PAUSE TestProAttach/success 452s === RUN TestProAttach/error_on_'pro_attach'_error 452s === PAUSE TestProAttach/error_on_'pro_attach'_error 452s === CONT TestProAttach/success 452s === CONT TestRealBackend 452s --- PASS: TestRealBackend (0.00s) 452s === CONT TestEnsureValidLandscapeConfig 452s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 452s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 452s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 452s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 452s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 452s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 452s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 452s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 452s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 452s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 452s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 452s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 452s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 452s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 452s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 452s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 452s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 452s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 452s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 452s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 452s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 452s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 452s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 452s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 452s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 452s === CONT TestLandscapeDisable 452s === RUN TestLandscapeDisable/Success 452s === PAUSE TestLandscapeDisable/Success 452s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 452s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 452s === CONT TestWindowsHostAddress 452s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 452s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 452s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 452s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 452s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 452s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 452s === RUN TestWindowsHostAddress/Without_NAT 452s === PAUSE TestWindowsHostAddress/Without_NAT 452s === RUN TestWindowsHostAddress/With_NAT 452s === PAUSE TestWindowsHostAddress/With_NAT 452s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 452s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 452s === CONT TestLandscapeEnable 452s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 452s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 452s === RUN TestLandscapeEnable/Regular_with_additional_keys 452s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 452s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 452s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 452s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 452s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 452s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 452s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 452s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 452s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 452s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 452s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 452s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 452s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 452s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 452s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 452s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 452s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 452s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 452s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 452s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 452s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 452s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 452s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 452s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 452s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 452s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 452s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 452s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 452s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 452s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 452s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 452s === CONT TestProDetach/success_on_attached_distro 452s === CONT TestProDetach/success_on_unattached_distro 452s === CONT TestInfo 452s === RUN TestInfo/Success 452s === PAUSE TestInfo/Success 452s === RUN TestInfo/Error_when_WslDistroName_fails 452s === PAUSE TestInfo/Error_when_WslDistroName_fails 452s === RUN TestInfo/Error_when_pro_status_command_fails 452s === PAUSE TestInfo/Error_when_pro_status_command_fails 452s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 452s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 452s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 452s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 452s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 452s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 452s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 452s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 452s === CONT TestProStatus 452s === RUN TestProStatus/error_on_'pro_attach'_error 452s === PAUSE TestProStatus/error_on_'pro_attach'_error 452s === RUN TestProStatus/success_on_unattached_distro 452s === PAUSE TestProStatus/success_on_unattached_distro 452s === RUN TestProStatus/success_on_attached_distro 452s === PAUSE TestProStatus/success_on_attached_distro 452s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 452s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 452s === CONT TestUserProfileDir 452s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 452s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 452s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 452s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 452s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 452s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 452s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 452s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 452s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 452s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 452s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 452s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 452s === RUN TestUserProfileDir/Error_on_wslpath_error 452s === PAUSE TestUserProfileDir/Error_on_wslpath_error 452s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 452s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 452s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 452s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 452s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 452s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 452s === RUN TestUserProfileDir/Error_on_cmd.exe_error 452s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 452s === CONT TestWslDistroName 452s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 452s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 452s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 452s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 452s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 452s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 452s === RUN TestWslDistroName/Success_using_wslpath 452s === PAUSE TestWslDistroName/Success_using_wslpath 452s === CONT TestProAttach/error_on_'pro_attach'_error 452s --- PASS: TestProDetach (0.00s) 452s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.85s) 452s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (2.28s) 452s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (2.39s) 452s --- PASS: TestProDetach/success_on_attached_distro (2.49s) 452s --- PASS: TestProDetach/success_on_unattached_distro (2.29s) 452s === CONT TestNew/Return_a_new_system 452s --- PASS: TestNew (0.00s) 452s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 452s --- PASS: TestNew/Return_a_new_system (0.00s) 452s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 452s --- PASS: TestProAttach (0.00s) 452s --- PASS: TestProAttach/success (2.35s) 452s --- PASS: TestProAttach/error_on_'pro_attach'_error (2.31s) 452s === CONT TestLandscapeDisable/Success 452s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 452s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 452s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 452s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 452s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 452s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 452s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 452s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 452s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 452s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 452s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 452s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 452s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_due_whitespace_changes 452s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 452s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 452s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 452s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 452s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 452s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 452s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 452s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 452s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 452s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 452s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (4.87s) 452s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (2.51s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (1.96s) 452s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (0.03s) 452s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (4.72s) 452s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (4.67s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (0.01s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.01s) 452s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (0.01s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (4.43s) 452s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (4.29s) 452s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes (1.91s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (2.11s) 452s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (2.11s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (0.02s) 452s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (2.45s) 452s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (2.43s) 452s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (4.59s) 452s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 452s --- PASS: TestLandscapeDisable (0.00s) 452s --- PASS: TestLandscapeDisable/Success (2.27s) 452s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.80s) 452s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 452s === CONT TestWindowsHostAddress/With_NAT 452s === CONT TestWindowsHostAddress/Without_NAT 452s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 452s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 452s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 452s --- PASS: TestWindowsHostAddress (0.00s) 452s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.75s) 452s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (2.42s) 452s --- PASS: TestWindowsHostAddress/With_NAT (2.47s) 452s --- PASS: TestWindowsHostAddress/Without_NAT (1.94s) 452s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.70s) 452s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.60s) 452s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 452s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 452s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 452s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 452s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 452s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 452s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 452s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 452s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 452s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 452s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 452s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 452s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 452s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 452s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 452s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 452s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 452s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 452s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 452s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 452s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 452s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 452s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 452s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 452s === CONT TestLandscapeEnable/Regular_with_additional_keys 452s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 452s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 452s === CONT TestInfo/Success 452s === NAME TestLandscapeEnable/Regular_with_additional_keys 452s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 452s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 452s --- PASS: TestLandscapeEnable (0.00s) 452s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (2.24s) 452s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.01s) 452s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (2.23s) 452s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (2.39s) 452s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (4.80s) 452s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.79s) 452s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (4.50s) 452s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (4.46s) 452s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (1.96s) 452s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (3.56s) 452s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (1.29s) 452s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (3.12s) 452s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (1.31s) 452s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 452s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (3.48s) 452s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (3.31s) 452s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 452s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 452s === CONT TestInfo/Error_when_pro_status_command_fails 452s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 452s === CONT TestInfo/Error_when_WslDistroName_fails 452s === CONT TestProStatus/error_on_'pro_attach'_error 452s --- PASS: TestInfo (0.00s) 452s --- PASS: TestInfo/Success (1.35s) 452s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.05s) 452s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.18s) 452s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.11s) 452s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.09s) 452s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.14s) 452s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.12s) 452s === CONT TestProStatus/success_on_attached_distro 452s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 452s === CONT TestProStatus/success_on_unattached_distro 452s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 452s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 452s --- PASS: TestProStatus (0.00s) 452s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.07s) 452s --- PASS: TestProStatus/success_on_attached_distro (1.20s) 452s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.13s) 452s --- PASS: TestProStatus/success_on_unattached_distro (1.17s) 452s === CONT TestUserProfileDir/Error_on_cmd.exe_error 452s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 452s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 452s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 452s === CONT TestUserProfileDir/Error_on_wslpath_error 452s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 452s system_test.go:245: Removing default proc/mounts 452s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 452s system_test.go:245: Removing default proc/mounts 452s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 452s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 452s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 452s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 452s === CONT TestWslDistroName/Success_using_wslpath 452s --- PASS: TestUserProfileDir (0.00s) 452s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (1.10s) 452s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 452s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.08s) 452s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (2.23s) 452s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (2.31s) 452s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 452s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 452s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.25s) 452s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.30s) 452s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.31s) 452s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.34s) 452s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 452s --- PASS: TestWslDistroName (0.00s) 452s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.15s) 452s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 452s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.11s) 452s --- PASS: TestWslDistroName/Success_using_wslpath (1.16s) 452s PASS 452s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 73.775s 452s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 453s autopkgtest [09:19:28]: test command1: -----------------------] 453s command1 PASS 453s autopkgtest [09:19:28]: test command1: - - - - - - - - - - results - - - - - - - - - - 454s autopkgtest [09:19:29]: @@@@@@@@@@@@@@@@@@@@ summary 454s command1 PASS