0s autopkgtest [08:20:13]: starting date and time: 2026-02-03 08:20:13+0000 0s autopkgtest [08:20:13]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:20:13]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.z76cw6ib/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:wsl-pro-service --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=wsl-pro-service/0.1.18build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-arm64-17.secgroup --name adt-resolute-arm64-wsl-pro-service-20260203-082013-juju-7f2275-prod-proposed-migration-environment-2-ceccce2b-1301-46f3-949b-a22e281f8e78 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-wsl-pro-service-20260203-082013-juju-7f2275-prod-proposed-migration-environment-2-ceccce2b-1301-46f3-949b-a22e281f8e78 from image adt/ubuntu-resolute-arm64-server-20260202.img (UUID e5b8703b-d12e-4238-a70e-a72a9b0d7948)... 58s autopkgtest [08:21:11]: testbed dpkg architecture: arm64 58s autopkgtest [08:21:11]: testbed apt version: 3.1.14 59s autopkgtest [08:21:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [08:21:12]: testbed release detected to be: None 60s autopkgtest [08:21:13]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 60s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 60s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 60s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 60s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1270 kB] 60s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 60s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [27.8 kB] 60s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [269 kB] 60s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [308 kB] 60s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7916 B] 60s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 60s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 60s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1115 kB] 60s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [27.6 kB] 60s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [21.5 kB] 60s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [744 B] 63s Fetched 3231 kB in 1s (4795 kB/s) 64s Reading package lists... 65s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 65s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 65s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 65s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Reading package lists... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 67s The following packages will be upgraded: 67s cloud-init cloud-init-base libexpat1 liblocale-gettext-perl systemd-hwe-hwdb 67s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s Need to get 731 kB of archives. 67s After this operation, 3072 B of additional disk space will be used. 67s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libexpat1 arm64 2.7.4-1 [85.7 kB] 67s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 liblocale-gettext-perl arm64 1.07-8 [15.6 kB] 67s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 systemd-hwe-hwdb all 259.0.1 [3152 B] 67s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 cloud-init-base all 25.4~4g26c7d38d-0ubuntu1 [624 kB] 67s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 cloud-init all 25.4~4g26c7d38d-0ubuntu1 [2120 B] 67s dpkg-preconfigure: unable to re-open stdin: No such file or directory 67s Fetched 731 kB in 0s (2106 kB/s) 68s (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 ... 89359 files and directories currently installed.) 68s Preparing to unpack .../libexpat1_2.7.4-1_arm64.deb ... 68s Unpacking libexpat1:arm64 (2.7.4-1) over (2.7.3-1) ... 68s Preparing to unpack .../liblocale-gettext-perl_1.07-8_arm64.deb ... 68s Unpacking liblocale-gettext-perl (1.07-8) over (1.07-7build1) ... 68s Preparing to unpack .../systemd-hwe-hwdb_259.0.1_all.deb ... 68s Unpacking systemd-hwe-hwdb (259.0.1) over (257.7.1) ... 68s Preparing to unpack .../cloud-init-base_25.4~4g26c7d38d-0ubuntu1_all.deb ... 68s Unpacking cloud-init-base (25.4~4g26c7d38d-0ubuntu1) over (25.4~3g78f6f15e-0ubuntu1) ... 69s Preparing to unpack .../cloud-init_25.4~4g26c7d38d-0ubuntu1_all.deb ... 69s Unpacking cloud-init (25.4~4g26c7d38d-0ubuntu1) over (25.4~3g78f6f15e-0ubuntu1) ... 69s Setting up libexpat1:arm64 (2.7.4-1) ... 69s Setting up cloud-init-base (25.4~4g26c7d38d-0ubuntu1) ... 69s Clearing ignored debconf value cloud-init/datasources 69s Encountered debconf setting for cloud-init-base/datasources. 70s Setting up systemd-hwe-hwdb (259.0.1) ... 71s Setting up liblocale-gettext-perl (1.07-8) ... 71s Setting up cloud-init (25.4~4g26c7d38d-0ubuntu1) ... 71s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 71s Processing triggers for man-db (2.13.1-1) ... 72s Processing triggers for udev (259-1ubuntu3) ... 73s Processing triggers for libc-bin (2.42-2ubuntu4) ... 73s autopkgtest [08:21:26]: upgrading testbed (apt dist-upgrade and autopurge) 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Calculating upgrade... 74s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 74s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Solving dependencies... 75s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 75s autopkgtest [08:21:28]: rebooting testbed after setup commands that affected boot 113s autopkgtest [08:22:06]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 116s autopkgtest [08:22:09]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 117s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (dsc) [2088 B] 117s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (tar) [1792 kB] 118s gpgv: Signature made Mon Feb 2 20:53:03 2026 UTC 118s gpgv: using RSA key 92FE4F8B40CADC4ECCD60C1F5961FDBE9C619809 118s gpgv: Can't check signature: No public key 118s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.18build1.dsc: no acceptable signature found 118s autopkgtest [08:22:11]: testing package wsl-pro-service version 0.1.18build1 118s autopkgtest [08:22:11]: build not needed 119s autopkgtest [08:22:12]: test command1: preparing testbed 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s Solving dependencies... 120s The following NEW packages will be installed: 120s autoconf automake autopoint autotools-dev binutils-gold 120s binutils-gold-aarch64-linux-gnu build-essential cpp cpp-15 120s cpp-15-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper debugedit dh-apport 120s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 120s g++-15-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-15 120s gcc-15-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext golang-1.25-go 120s golang-1.25-src golang-go golang-src intltool-debian libarchive-zip-perl 120s libasan8 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl 120s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libhwasan0 libisl23 120s libitm1 liblsan0 libmpc3 libstdc++-15-dev libtool libtsan2 libubsan1 120s linux-libc-dev m4 po-debconf rpcsvc-proto 120s 0 upgraded, 55 newly installed, 0 to remove and 0 not upgraded. 120s Need to get 114 MB of archives. 120s After this operation, 472 MB of additional disk space will be used. 120s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 m4 arm64 1.4.20-2 [213 kB] 120s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 autoconf all 2.72-3.1ubuntu1 [384 kB] 120s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 autotools-dev all 20240727.1 [43.4 kB] 120s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 automake all 1:1.18.1-3build1 [582 kB] 120s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 autopoint all 0.23.2-1 [620 kB] 121s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold-aarch64-linux-gnu arm64 2.44-3 [2138 kB] 121s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold arm64 2.44-3 [2172 B] 121s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 libc-dev-bin arm64 2.42-2ubuntu4 [22.5 kB] 121s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 linux-libc-dev arm64 6.18.0-9.9 [1834 kB] 121s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 libcrypt-dev arm64 1:4.5.1-1 [123 kB] 121s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 rpcsvc-proto arm64 1.4.3-1 [65.7 kB] 121s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 libc6-dev arm64 2.42-2ubuntu4 [1765 kB] 121s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 libisl23 arm64 0.27-1build1 [676 kB] 121s Get:14 http://ftpmaster.internal/ubuntu resolute/main arm64 libmpc3 arm64 1.3.1-2 [55.6 kB] 121s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [11.7 MB] 121s Get:16 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15 arm64 15.2.0-12ubuntu1 [1030 B] 121s Get:17 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [5736 B] 121s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp arm64 4:15.2.0-4ubuntu1 [22.4 kB] 121s Get:19 http://ftpmaster.internal/ubuntu resolute/main arm64 libcc1-0 arm64 15.2.0-12ubuntu1 [49.0 kB] 121s Get:20 http://ftpmaster.internal/ubuntu resolute/main arm64 libgomp1 arm64 15.2.0-12ubuntu1 [147 kB] 121s Get:21 http://ftpmaster.internal/ubuntu resolute/main arm64 libitm1 arm64 15.2.0-12ubuntu1 [27.8 kB] 121s Get:22 http://ftpmaster.internal/ubuntu resolute/main arm64 libasan8 arm64 15.2.0-12ubuntu1 [2920 kB] 121s Get:23 http://ftpmaster.internal/ubuntu resolute/main arm64 liblsan0 arm64 15.2.0-12ubuntu1 [1316 kB] 121s Get:24 http://ftpmaster.internal/ubuntu resolute/main arm64 libtsan2 arm64 15.2.0-12ubuntu1 [2688 kB] 121s Get:25 http://ftpmaster.internal/ubuntu resolute/main arm64 libubsan1 arm64 15.2.0-12ubuntu1 [1175 kB] 121s Get:26 http://ftpmaster.internal/ubuntu resolute/main arm64 libhwasan0 arm64 15.2.0-12ubuntu1 [1638 kB] 121s Get:27 http://ftpmaster.internal/ubuntu resolute/main arm64 libgcc-15-dev arm64 15.2.0-12ubuntu1 [2600 kB] 121s Get:28 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [23.1 MB] 122s Get:29 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15 arm64 15.2.0-12ubuntu1 [519 kB] 122s Get:30 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [1206 B] 122s Get:31 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc arm64 4:15.2.0-4ubuntu1 [5016 B] 122s Get:32 http://ftpmaster.internal/ubuntu resolute/main arm64 libstdc++-15-dev arm64 15.2.0-12ubuntu1 [2549 kB] 122s Get:33 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15-aarch64-linux-gnu arm64 15.2.0-12ubuntu1 [13.2 MB] 124s Get:34 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15 arm64 15.2.0-12ubuntu1 [25.3 kB] 124s Get:35 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [956 B] 124s Get:36 http://ftpmaster.internal/ubuntu resolute/main arm64 g++ arm64 4:15.2.0-4ubuntu1 [1080 B] 124s Get:37 http://ftpmaster.internal/ubuntu resolute/main arm64 build-essential arm64 12.12ubuntu2 [5254 B] 124s Get:38 http://ftpmaster.internal/ubuntu resolute/main arm64 libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 124s Get:39 http://ftpmaster.internal/ubuntu resolute/main arm64 libtool all 2.5.4-9 [169 kB] 124s Get:40 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-autoreconf all 21 [12.5 kB] 124s Get:41 http://ftpmaster.internal/ubuntu resolute/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 124s Get:42 http://ftpmaster.internal/ubuntu resolute/main arm64 libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 124s Get:43 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 124s Get:44 http://ftpmaster.internal/ubuntu resolute/main arm64 debugedit arm64 1:5.2-3 [49.1 kB] 124s Get:45 http://ftpmaster.internal/ubuntu resolute/main arm64 dwz arm64 0.16-2 [113 kB] 124s Get:46 http://ftpmaster.internal/ubuntu resolute/main arm64 gettext arm64 0.23.2-1 [998 kB] 124s Get:47 http://ftpmaster.internal/ubuntu resolute/main arm64 intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 124s Get:48 http://ftpmaster.internal/ubuntu resolute/main arm64 po-debconf all 1.0.22 [215 kB] 124s Get:49 http://ftpmaster.internal/ubuntu resolute/main arm64 debhelper all 13.28ubuntu1 [916 kB] 124s Get:50 http://ftpmaster.internal/ubuntu resolute/universe arm64 dh-apport all 2.33.1-0ubuntu3 [20.2 kB] 124s Get:51 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 124s Get:52 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-go arm64 1.25.0-0ubuntu1 [16.0 MB] 125s Get:53 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-src all 2:1.25~1 [5206 B] 125s Get:54 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-go arm64 2:1.25~1 [44.1 kB] 125s Get:55 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-golang all 1.63build1 [25.0 kB] 125s Fetched 114 MB in 4s (26.2 MB/s) 125s Selecting previously unselected package m4. 125s (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.) 125s Preparing to unpack .../00-m4_1.4.20-2_arm64.deb ... 125s Unpacking m4 (1.4.20-2) ... 125s Selecting previously unselected package autoconf. 125s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 125s Unpacking autoconf (2.72-3.1ubuntu1) ... 126s Selecting previously unselected package autotools-dev. 126s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 126s Unpacking autotools-dev (20240727.1) ... 126s Selecting previously unselected package automake. 126s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ... 126s Unpacking automake (1:1.18.1-3build1) ... 126s Selecting previously unselected package autopoint. 126s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ... 126s Unpacking autopoint (0.23.2-1) ... 126s Selecting previously unselected package binutils-gold-aarch64-linux-gnu. 126s Preparing to unpack .../05-binutils-gold-aarch64-linux-gnu_2.44-3_arm64.deb ... 126s Unpacking binutils-gold-aarch64-linux-gnu (2.44-3) ... 126s Selecting previously unselected package binutils-gold. 126s Preparing to unpack .../06-binutils-gold_2.44-3_arm64.deb ... 126s Unpacking binutils-gold (2.44-3) ... 126s Selecting previously unselected package libc-dev-bin. 126s Preparing to unpack .../07-libc-dev-bin_2.42-2ubuntu4_arm64.deb ... 126s Unpacking libc-dev-bin (2.42-2ubuntu4) ... 126s Selecting previously unselected package linux-libc-dev:arm64. 126s Preparing to unpack .../08-linux-libc-dev_6.18.0-9.9_arm64.deb ... 126s Unpacking linux-libc-dev:arm64 (6.18.0-9.9) ... 126s Selecting previously unselected package libcrypt-dev:arm64. 126s Preparing to unpack .../09-libcrypt-dev_1%3a4.5.1-1_arm64.deb ... 126s Unpacking libcrypt-dev:arm64 (1:4.5.1-1) ... 126s Selecting previously unselected package rpcsvc-proto. 126s Preparing to unpack .../10-rpcsvc-proto_1.4.3-1_arm64.deb ... 126s Unpacking rpcsvc-proto (1.4.3-1) ... 126s Selecting previously unselected package libc6-dev:arm64. 126s Preparing to unpack .../11-libc6-dev_2.42-2ubuntu4_arm64.deb ... 126s Unpacking libc6-dev:arm64 (2.42-2ubuntu4) ... 126s Selecting previously unselected package libisl23:arm64. 126s Preparing to unpack .../12-libisl23_0.27-1build1_arm64.deb ... 126s Unpacking libisl23:arm64 (0.27-1build1) ... 126s Selecting previously unselected package libmpc3:arm64. 126s Preparing to unpack .../13-libmpc3_1.3.1-2_arm64.deb ... 126s Unpacking libmpc3:arm64 (1.3.1-2) ... 126s Selecting previously unselected package cpp-15-aarch64-linux-gnu. 126s Preparing to unpack .../14-cpp-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 126s Unpacking cpp-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 126s Selecting previously unselected package cpp-15. 126s Preparing to unpack .../15-cpp-15_15.2.0-12ubuntu1_arm64.deb ... 126s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 126s Selecting previously unselected package cpp-aarch64-linux-gnu. 126s Preparing to unpack .../16-cpp-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 126s Unpacking cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 126s Selecting previously unselected package cpp. 126s Preparing to unpack .../17-cpp_4%3a15.2.0-4ubuntu1_arm64.deb ... 126s Unpacking cpp (4:15.2.0-4ubuntu1) ... 126s Selecting previously unselected package libcc1-0:arm64. 127s Preparing to unpack .../18-libcc1-0_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libcc1-0:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libgomp1:arm64. 127s Preparing to unpack .../19-libgomp1_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libgomp1:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libitm1:arm64. 127s Preparing to unpack .../20-libitm1_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libitm1:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libasan8:arm64. 127s Preparing to unpack .../21-libasan8_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libasan8:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package liblsan0:arm64. 127s Preparing to unpack .../22-liblsan0_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking liblsan0:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libtsan2:arm64. 127s Preparing to unpack .../23-libtsan2_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libtsan2:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libubsan1:arm64. 127s Preparing to unpack .../24-libubsan1_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libubsan1:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libhwasan0:arm64. 127s Preparing to unpack .../25-libhwasan0_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libhwasan0:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package libgcc-15-dev:arm64. 127s Preparing to unpack .../26-libgcc-15-dev_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking libgcc-15-dev:arm64 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package gcc-15-aarch64-linux-gnu. 127s Preparing to unpack .../27-gcc-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking gcc-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package gcc-15. 127s Preparing to unpack .../28-gcc-15_15.2.0-12ubuntu1_arm64.deb ... 127s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 127s Selecting previously unselected package gcc-aarch64-linux-gnu. 127s Preparing to unpack .../29-gcc-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 127s Unpacking gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 127s Selecting previously unselected package gcc. 128s Preparing to unpack .../30-gcc_4%3a15.2.0-4ubuntu1_arm64.deb ... 128s Unpacking gcc (4:15.2.0-4ubuntu1) ... 128s Selecting previously unselected package libstdc++-15-dev:arm64. 128s Preparing to unpack .../31-libstdc++-15-dev_15.2.0-12ubuntu1_arm64.deb ... 128s Unpacking libstdc++-15-dev:arm64 (15.2.0-12ubuntu1) ... 128s Selecting previously unselected package g++-15-aarch64-linux-gnu. 128s Preparing to unpack .../32-g++-15-aarch64-linux-gnu_15.2.0-12ubuntu1_arm64.deb ... 128s Unpacking g++-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 128s Selecting previously unselected package g++-15. 128s Preparing to unpack .../33-g++-15_15.2.0-12ubuntu1_arm64.deb ... 128s Unpacking g++-15 (15.2.0-12ubuntu1) ... 128s Selecting previously unselected package g++-aarch64-linux-gnu. 128s Preparing to unpack .../34-g++-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 128s Unpacking g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 128s Selecting previously unselected package g++. 128s Preparing to unpack .../35-g++_4%3a15.2.0-4ubuntu1_arm64.deb ... 128s Unpacking g++ (4:15.2.0-4ubuntu1) ... 128s Selecting previously unselected package build-essential. 128s Preparing to unpack .../36-build-essential_12.12ubuntu2_arm64.deb ... 128s Unpacking build-essential (12.12ubuntu2) ... 128s Selecting previously unselected package libdebhelper-perl. 128s Preparing to unpack .../37-libdebhelper-perl_13.28ubuntu1_all.deb ... 128s Unpacking libdebhelper-perl (13.28ubuntu1) ... 128s Selecting previously unselected package libtool. 128s Preparing to unpack .../38-libtool_2.5.4-9_all.deb ... 128s Unpacking libtool (2.5.4-9) ... 128s Selecting previously unselected package dh-autoreconf. 128s Preparing to unpack .../39-dh-autoreconf_21_all.deb ... 128s Unpacking dh-autoreconf (21) ... 128s Selecting previously unselected package libarchive-zip-perl. 128s Preparing to unpack .../40-libarchive-zip-perl_1.68-1_all.deb ... 128s Unpacking libarchive-zip-perl (1.68-1) ... 128s Selecting previously unselected package libfile-stripnondeterminism-perl. 128s Preparing to unpack .../41-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 128s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 128s Selecting previously unselected package dh-strip-nondeterminism. 128s Preparing to unpack .../42-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 128s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 128s Selecting previously unselected package debugedit. 128s Preparing to unpack .../43-debugedit_1%3a5.2-3_arm64.deb ... 128s Unpacking debugedit (1:5.2-3) ... 128s Selecting previously unselected package dwz. 128s Preparing to unpack .../44-dwz_0.16-2_arm64.deb ... 128s Unpacking dwz (0.16-2) ... 128s Selecting previously unselected package gettext. 128s Preparing to unpack .../45-gettext_0.23.2-1_arm64.deb ... 128s Unpacking gettext (0.23.2-1) ... 128s Selecting previously unselected package intltool-debian. 128s Preparing to unpack .../46-intltool-debian_0.35.0+20060710.6build1_all.deb ... 128s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 128s Selecting previously unselected package po-debconf. 128s Preparing to unpack .../47-po-debconf_1.0.22_all.deb ... 128s Unpacking po-debconf (1.0.22) ... 128s Selecting previously unselected package debhelper. 129s Preparing to unpack .../48-debhelper_13.28ubuntu1_all.deb ... 129s Unpacking debhelper (13.28ubuntu1) ... 129s Selecting previously unselected package dh-apport. 129s Preparing to unpack .../49-dh-apport_2.33.1-0ubuntu3_all.deb ... 129s Unpacking dh-apport (2.33.1-0ubuntu3) ... 129s Selecting previously unselected package golang-1.25-src. 129s Preparing to unpack .../50-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 129s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 130s Selecting previously unselected package golang-1.25-go. 130s Preparing to unpack .../51-golang-1.25-go_1.25.0-0ubuntu1_arm64.deb ... 130s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 130s Selecting previously unselected package golang-src. 130s Preparing to unpack .../52-golang-src_2%3a1.25~1_all.deb ... 130s Unpacking golang-src (2:1.25~1) ... 130s Selecting previously unselected package golang-go:arm64. 130s Preparing to unpack .../53-golang-go_2%3a1.25~1_arm64.deb ... 131s Unpacking golang-go:arm64 (2:1.25~1) ... 131s Selecting previously unselected package dh-golang. 131s Preparing to unpack .../54-dh-golang_1.63build1_all.deb ... 131s Unpacking dh-golang (1.63build1) ... 131s Setting up libarchive-zip-perl (1.68-1) ... 131s Setting up libdebhelper-perl (13.28ubuntu1) ... 131s Setting up linux-libc-dev:arm64 (6.18.0-9.9) ... 131s Setting up m4 (1.4.20-2) ... 131s Setting up binutils-gold-aarch64-linux-gnu (2.44-3) ... 131s Setting up libgomp1:arm64 (15.2.0-12ubuntu1) ... 131s Setting up dh-apport (2.33.1-0ubuntu3) ... 131s Setting up autotools-dev (20240727.1) ... 131s Setting up rpcsvc-proto (1.4.3-1) ... 131s Setting up libmpc3:arm64 (1.3.1-2) ... 131s Setting up autopoint (0.23.2-1) ... 131s Setting up autoconf (2.72-3.1ubuntu1) ... 131s Setting up libubsan1:arm64 (15.2.0-12ubuntu1) ... 131s Setting up dwz (0.16-2) ... 131s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 131s Setting up libhwasan0:arm64 (15.2.0-12ubuntu1) ... 131s Setting up libcrypt-dev:arm64 (1:4.5.1-1) ... 131s Setting up libasan8:arm64 (15.2.0-12ubuntu1) ... 131s Setting up debugedit (1:5.2-3) ... 131s Setting up libtsan2:arm64 (15.2.0-12ubuntu1) ... 131s Setting up libisl23:arm64 (0.27-1build1) ... 131s Setting up libc-dev-bin (2.42-2ubuntu4) ... 131s Setting up golang-src (2:1.25~1) ... 131s Setting up libcc1-0:arm64 (15.2.0-12ubuntu1) ... 131s Setting up liblsan0:arm64 (15.2.0-12ubuntu1) ... 131s Setting up libitm1:arm64 (15.2.0-12ubuntu1) ... 131s Setting up automake (1:1.18.1-3build1) ... 131s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 131s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 131s Setting up cpp-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 131s Setting up gettext (0.23.2-1) ... 131s Setting up libgcc-15-dev:arm64 (15.2.0-12ubuntu1) ... 131s Setting up binutils-gold (2.44-3) ... 131s Setting up intltool-debian (0.35.0+20060710.6build1) ... 131s Setting up cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 131s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 131s Setting up cpp-15 (15.2.0-12ubuntu1) ... 131s Setting up cpp (4:15.2.0-4ubuntu1) ... 131s Setting up libc6-dev:arm64 (2.42-2ubuntu4) ... 131s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 131s Setting up gcc-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 131s Setting up golang-go:arm64 (2:1.25~1) ... 131s Setting up po-debconf (1.0.22) ... 131s Setting up gcc-15 (15.2.0-12ubuntu1) ... 131s Setting up libstdc++-15-dev:arm64 (15.2.0-12ubuntu1) ... 131s Setting up g++-15-aarch64-linux-gnu (15.2.0-12ubuntu1) ... 131s Setting up g++-15 (15.2.0-12ubuntu1) ... 131s Setting up gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 131s Setting up libtool (2.5.4-9) ... 131s Setting up gcc (4:15.2.0-4ubuntu1) ... 131s Setting up dh-autoreconf (21) ... 131s Setting up g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 131s Setting up debhelper (13.28ubuntu1) ... 131s Setting up g++ (4:15.2.0-4ubuntu1) ... 131s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 131s Setting up build-essential (12.12ubuntu2) ... 131s Setting up dh-golang (1.63build1) ... 131s Processing triggers for man-db (2.13.1-1) ... 132s Processing triggers for install-info (7.2-5) ... 133s Processing triggers for libc-bin (2.42-2ubuntu4) ... 133s autopkgtest [08:22:26]: test command1: ./debian/tests/test 133s autopkgtest [08:22:26]: test command1: [----------------------- 176s === RUN TestRunSignal 176s === RUN TestRunSignal/Send_SIGINT_exits 176s INFO Starting WSL Pro Service version Dev 176s === RUN TestRunSignal/Send_SIGTERM_exits 176s INFO Starting WSL Pro Service version Dev 176s --- PASS: TestRunSignal (0.20s) 176s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 176s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 176s === RUN TestRun 176s === PAUSE TestRun 176s === CONT TestRun 176s === RUN TestRun/Run_and_exit_successfully 176s === PAUSE TestRun/Run_and_exit_successfully 176s === RUN TestRun/Run_and_return_error 176s === PAUSE TestRun/Run_and_return_error 176s === RUN TestRun/Run_and_return_usage_error 176s === PAUSE TestRun/Run_and_return_usage_error 176s === RUN TestRun/Run_and_usage_error_only_does_not_fail 176s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 176s === CONT TestRun/Run_and_exit_successfully 176s INFO Starting WSL Pro Service version Dev 176s === CONT TestRun/Run_and_usage_error_only_does_not_fail 176s INFO Starting WSL Pro Service version Dev 176s === CONT TestRun/Run_and_return_usage_error 176s INFO Starting WSL Pro Service version Dev 176s === CONT TestRun/Run_and_return_error 176s INFO Starting WSL Pro Service version Dev 176s ERROR context.Background Error requested 176s ERROR context.Background Error requested 176s --- PASS: TestRun (0.00s) 176s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 176s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 176s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 176s --- PASS: TestRun/Run_and_return_error (0.10s) 176s PASS 176s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.423s 190s === RUN TestHelp 190s --- PASS: TestHelp (0.00s) 190s === RUN TestCompletion 190s --- PASS: TestCompletion (0.00s) 190s === RUN TestVersion 190s --- PASS: TestVersion (0.00s) 190s === RUN TestConfigBadArg 190s --- PASS: TestConfigBadArg (0.00s) 190s === RUN TestConfigArg 190s --- PASS: TestConfigArg (0.00s) 190s === RUN TestConfigAutoDetect 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 190s --- PASS: TestConfigAutoDetect (0.00s) 190s === RUN TestNoUsageError 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 190s --- PASS: TestNoUsageError (0.00s) 190s === RUN TestUsageError 190s === PAUSE TestUsageError 190s === RUN TestCanQuitWhenExecute 190s === PAUSE TestCanQuitWhenExecute 190s === RUN TestCanQuitTwice 190s === PAUSE TestCanQuitTwice 190s === RUN TestAppCanQuitWithoutExecute 190s === PAUSE TestAppCanQuitWithoutExecute 190s === RUN TestAppRunFailsOnComponentsCreationAndQuit 190s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 190s === RUN TestAppGetRootCmd 190s === PAUSE TestAppGetRootCmd 190s === RUN TestWithProMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithProMock (0.00s) 190s === RUN TestWithWslPathMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithWslPathMock (0.00s) 190s === RUN TestWithWslInfoMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithWslInfoMock (0.00s) 190s === RUN TestWithCmdExeMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithCmdExeMock (0.00s) 190s === CONT TestUsageError 190s === CONT TestAppCanQuitWithoutExecute 190s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 190s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 190s === CONT TestAppGetRootCmd 190s --- PASS: TestUsageError (0.00s) 190s === CONT TestCanQuitTwice 190s --- PASS: TestAppGetRootCmd (0.00s) 190s === CONT TestAppRunFailsOnComponentsCreationAndQuit 190s time="2026-02-03T08:23:09Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39387" 190s time="2026-02-03T08:23:09Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build482186372/b385/wsl-pro-service.yaml" 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:74 New() Building new daemon" 190s time="2026-02-03T08:23:09Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build482186372/b385/wsl-pro-service.yaml" 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 190s time="2026-02-03T08:23:09Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:74 New() Building new daemon" 190s time="2026-02-03T08:23:14Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 190s time="2026-02-03T08:23:14Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 190s time="2026-02-03T08:23:14Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5795" 190s time="2026-02-03T08:23:14Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 190s time="2026-02-03T08:23:14Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 190s time="2026-02-03T08:23:14Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5795" 190s time="2026-02-03T08:23:14Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:14Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:17Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (7.55s) 190s === CONT TestCanQuitWhenExecute 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35409" 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build482186372/b385/wsl-pro-service.yaml" 190s time="2026-02-03T08:23:17Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 190s time="2026-02-03T08:23:17Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:74 New() Building new daemon" 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:33301" 190s time="2026-02-03T08:23:17Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 190s time="2026-02-03T08:23:17Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 190s time="2026-02-03T08:23:17Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 190s time="2026-02-03T08:23:20Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 190s time="2026-02-03T08:23:20Z" level=warning msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:196 (*Daemon).serveOnce() Daemon: disconnected from Windows host" 190s time="2026-02-03T08:23:20Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 190s time="2026-02-03T08:23:20Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s time="2026-02-03T08:23:20Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:20Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:20Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s time="2026-02-03T08:23:20Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.9Rvbji/build.LGn/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 190s time="2026-02-03T08:23:20Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:20Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:20Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s --- PASS: TestCanQuitTwice (10.77s) 190s time="2026-02-03T08:23:20Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.9Rvbji/build.LGn/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 190s time="2026-02-03T08:23:20Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.9Rvbji/build.LGn/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 190s time="2026-02-03T08:23:21Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 190s time="2026-02-03T08:23:21Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 190s time="2026-02-03T08:23:21Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 5795" 190s time="2026-02-03T08:23:22Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:22Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:23Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:35409" 190s time="2026-02-03T08:23:23Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 190s time="2026-02-03T08:23:23Z" level=warning msg="/tmp/autopkgtest.9Rvbji/build.LGn/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:35409: connect: connection refused\"" 190s time="2026-02-03T08:23:23Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 190s time="2026-02-03T08:23:23Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s time="2026-02-03T08:23:23Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 190s time="2026-02-03T08:23:23Z" level=info msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 190s time="2026-02-03T08:23:23Z" level=debug msg="/tmp/autopkgtest.9Rvbji/build.LGn/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 190s --- PASS: TestCanQuitWhenExecute (5.95s) 190s PASS 190s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 13.545s 190s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 190s === RUN TestApplyProToken 190s === PAUSE TestApplyProToken 190s === RUN TestApplyLandscapeConfig 190s === PAUSE TestApplyLandscapeConfig 190s === RUN TestWithProMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithProMock (0.00s) 190s === RUN TestWithLandscapeConfigMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithLandscapeConfigMock (0.00s) 190s === RUN TestWithWslPathMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithWslPathMock (0.00s) 190s === RUN TestWithWslInfoMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithWslInfoMock (0.00s) 190s === RUN TestWithCmdExeMock 190s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 190s --- SKIP: TestWithCmdExeMock (0.00s) 190s === CONT TestApplyProToken 190s === CONT TestApplyLandscapeConfig 190s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 190s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 190s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 190s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 190s === RUN TestApplyProToken/Success_attaching 190s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 190s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 190s === PAUSE TestApplyProToken/Success_attaching 190s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 190s === RUN TestApplyProToken/Success_detaching 190s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 190s === PAUSE TestApplyProToken/Success_detaching 190s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 190s === RUN TestApplyProToken/Error_calling_pro_detach 190s === PAUSE TestApplyProToken/Error_calling_pro_detach 190s === RUN TestApplyProToken/Error_calling_pro_attach 190s === PAUSE TestApplyProToken/Error_calling_pro_attach 190s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 190s time="2026-02-03T08:23:10Z" level=info msg="ApplyLandscapeConfig: received config: registering" 190s time="2026-02-03T08:23:10Z" level=info msg="ApplyLandscapeConfig: received config: registering" 190s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 190s time="2026-02-03T08:23:13Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 190s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 190s time="2026-02-03T08:23:13Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 190s === CONT TestApplyProToken/Success_attaching 190s time="2026-02-03T08:23:15Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 190s --- PASS: TestApplyLandscapeConfig (0.00s) 190s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (2.35s) 190s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (2.45s) 190s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (2.17s) 190s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (2.46s) 190s === CONT TestApplyProToken/Error_calling_pro_attach 190s time="2026-02-03T08:23:15Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 190s === CONT TestApplyProToken/Error_calling_pro_detach 190s time="2026-02-03T08:23:20Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 190s === CONT TestApplyProToken/Success_detaching 190s time="2026-02-03T08:23:20Z" level=info msg="ApplyProToken: Received empty token: detaching" 190s --- PASS: TestApplyProToken (0.00s) 190s --- PASS: TestApplyProToken/Success_attaching (4.56s) 190s --- PASS: TestApplyProToken/Error_calling_pro_attach (4.44s) 190s --- PASS: TestApplyProToken/Success_detaching (1.47s) 190s --- PASS: TestApplyProToken/Error_calling_pro_detach (1.66s) 190s PASS 190s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 10.871s 190s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 327s === RUN TestNew 327s === PAUSE TestNew 327s === RUN TestServe 327s === PAUSE TestServe 327s === RUN TestServeAndQuit 327s === PAUSE TestServeAndQuit 327s === RUN TestRetryLogic 327s === PAUSE TestRetryLogic 327s === RUN TestReconnection 327s === PAUSE TestReconnection 327s === RUN TestWithProMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithProMock (0.00s) 327s === RUN TestWithWslPathMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslPathMock (0.00s) 327s === RUN TestWithWslInfoMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslInfoMock (0.00s) 327s === RUN TestWithCmdExeMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithCmdExeMock (0.00s) 327s === CONT TestNew 327s === CONT TestRetryLogic 327s === RUN TestRetryLogic/Without_retries 327s === PAUSE TestRetryLogic/Without_retries 327s === RUN TestRetryLogic/With_the_context_pre-cancelled 327s === PAUSE TestRetryLogic/With_the_context_pre-cancelled 327s === RUN TestRetryLogic/With_the_context_cancelled_while_waiting 327s === PAUSE TestRetryLogic/With_the_context_cancelled_while_waiting 327s === RUN TestRetryLogic/When_max_attempts_are_exhausted 327s === RUN TestNew/Success 327s === PAUSE TestRetryLogic/When_max_attempts_are_exhausted 327s === RUN TestRetryLogic/Error_only_when_action_errors 327s === PAUSE TestNew/Success 327s === PAUSE TestRetryLogic/Error_only_when_action_errors 327s === RUN TestNew/Error_when_WslPath_returns_error 327s === CONT TestServeAndQuit 327s === PAUSE TestNew/Error_when_WslPath_returns_error 327s === RUN TestServeAndQuit/Success_with_forceful_quit 327s === CONT TestServe 327s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 327s === PAUSE TestServeAndQuit/Success_with_forceful_quit 327s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 327s === RUN TestServe/No_connection_because_there_is_no_server 327s === RUN TestServeAndQuit/Success_with_double_quit 327s === PAUSE TestServeAndQuit/Success_with_double_quit 327s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 327s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 327s === PAUSE TestServe/No_connection_because_there_is_no_server 327s === RUN TestServeAndQuit/Success_with_graceful_quit 327s === PAUSE TestServeAndQuit/Success_with_graceful_quit 327s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 327s === CONT TestReconnection 327s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 327s === RUN TestReconnection/Success_connecting_after_failing_to_connect 327s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 327s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 327s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 327s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 327s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 327s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 327s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 327s === CONT TestRetryLogic/Without_retries 327s === RUN TestServe/Success_with_systemd_notifier_returning_true 327s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 327s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 327s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 327s === RUN TestServe/No_connection_because_the_port_file_has_port_0 327s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 327s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 327s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 327s === RUN TestServe/No_connection_because_there_are_no_certificates 327s === PAUSE TestServe/No_connection_because_there_are_no_certificates 327s === RUN TestServe/Error_because_the_context_is_pre-cancelled 327s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 327s === RUN TestServe/Error_because_the_notifier_returns_an_error 327s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 327s === RUN TestServe/Success 327s === PAUSE TestServe/Success 327s === RUN TestServe/Success_with_a_broken_Landscape_config 327s === PAUSE TestServe/Success_with_a_broken_Landscape_config 327s === RUN TestServe/No_connection_because_the_port_file_is_empty 327s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 327s === CONT TestRetryLogic/Error_only_when_action_errors 327s === CONT TestRetryLogic/When_max_attempts_are_exhausted 327s === CONT TestRetryLogic/With_the_context_cancelled_while_waiting 327s === CONT TestRetryLogic/With_the_context_pre-cancelled 327s === CONT TestNew/Success 327s time="2026-02-03T08:23:23Z" level=debug msg="Building new daemon" 327s --- PASS: TestRetryLogic (0.00s) 327s --- PASS: TestRetryLogic/Error_only_when_action_errors (0.00s) 327s --- PASS: TestRetryLogic/When_max_attempts_are_exhausted (0.43s) 327s --- PASS: TestRetryLogic/With_the_context_cancelled_while_waiting (0.03s) 327s --- PASS: TestRetryLogic/With_the_context_pre-cancelled (0.00s) 327s --- PASS: TestRetryLogic/Without_retries (0.70s) 327s === CONT TestNew/Error_when_WslPath_returns_error 327s time="2026-02-03T08:23:23Z" level=debug msg="Building new daemon" 327s === CONT TestServeAndQuit/Success_with_forceful_quit 327s time="2026-02-03T08:23:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44997" 327s time="2026-02-03T08:23:27Z" level=debug msg="Building new daemon" 327s --- PASS: TestNew (0.00s) 327s --- PASS: TestNew/Success (4.13s) 327s --- PASS: TestNew/Error_when_WslPath_returns_error (4.28s) 327s === CONT TestReconnection/Success_connecting_after_failing_to_connect 327s time="2026-02-03T08:23:28Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:32Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:23:32Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:32Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:32Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:32Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:23:32Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:23:32Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:32Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:32Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:32Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:23:32Z" 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_connect1123484844/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect1123484844/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 327s time="2026-02-03T08:23:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:32Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:23:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44317" 327s time="2026-02-03T08:23:33Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44997" 327s time="2026-02-03T08:23:33Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:33Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:23:33Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:33Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:33Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:33Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:23:34Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:34Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:34Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:34Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:35Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44317" 327s time="2026-02-03T08:23:35Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:35Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:23:35Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:35Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:35Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:35Z" level=info msg="Stopping active requests." 327s time="2026-02-03T08:23:35Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:35Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:35Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:23:35Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:23:35Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:23:35Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:35Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:35Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s === CONT TestServeAndQuit/Success_with_graceful_quit 327s time="2026-02-03T08:23:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37137" 327s time="2026-02-03T08:23:35Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:36Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:36Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:36Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:36Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:36Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:36Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:36Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:36Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:36Z" level=info msg="Stopping active requests." 327s time="2026-02-03T08:23:36Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:36Z" level=info msg="Reconnecting to Windows host in 2 seconds" 327s time="2026-02-03T08:23:36Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:23:36Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:23:36Z" level=debug msg="All connections have now ended." 327s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 327s time="2026-02-03T08:23:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42575" 327s time="2026-02-03T08:23:36Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:37Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:23:37Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:37Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:37Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:37Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:23:38Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37137" 327s time="2026-02-03T08:23:38Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:38Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:23:38Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:38Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:39Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:39Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:39Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:39Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:39Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s === CONT TestServeAndQuit/Success_with_double_quit 327s time="2026-02-03T08:23:39Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43179" 327s time="2026-02-03T08:23:39Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:40Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:40Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:40Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:40Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:40Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:40Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:40Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:40Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:40Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:23:40Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:23:40Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:23:40Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:40Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:40Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 327s time="2026-02-03T08:23:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37225" 327s time="2026-02-03T08:23:40Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:41Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:23:41Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:41Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:41Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:41Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:23:42Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43179" 327s time="2026-02-03T08:23:42Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:42Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:23:42Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:42Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:43Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:43Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:43Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:43Z" 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" 327s time="2026-02-03T08:23:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:43Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:43Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:43Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:43Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:43Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:43Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:43Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:43Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:23:43Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:23:43Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:23:43Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:43Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:43Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:43Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:43Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:43Z" level=debug msg="All connections have now ended." 327s --- PASS: TestServeAndQuit (0.00s) 327s --- PASS: TestServeAndQuit/Success_with_forceful_quit (7.60s) 327s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.49s) 327s --- PASS: TestServeAndQuit/Success_with_graceful_quit (5.41s) 327s --- PASS: TestServeAndQuit/Success_with_double_quit (4.80s) 327s === CONT TestServe/No_connection_because_the_port_file_is_empty 327s time="2026-02-03T08:23:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41119" 327s time="2026-02-03T08:23:43Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:44Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:44Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:44Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/Success_with_a_broken_Landscape_config 327s time="2026-02-03T08:23:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37897" 327s time="2026-02-03T08:23:44Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:46Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:46Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:46Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:46Z" 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" 327s time="2026-02-03T08:23:46Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:46Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:46Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: read /tmp/TestServeSuccess_with_a_broken_Landscape_config84484889/001/etc/landscape/client.conf: is a directory" 327s time="2026-02-03T08:23:46Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:47Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:47Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:47Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/Success 327s time="2026-02-03T08:23:47Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41727" 327s time="2026-02-03T08:23:47Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:47Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37897" 327s time="2026-02-03T08:23:47Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:47Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:47Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:48Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:48Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:48Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:48Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:49Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:49Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:49Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:50Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:41727" 327s time="2026-02-03T08:23:50Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:23:50Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:23:50Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:23:50Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:50Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:50Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:50Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:23:50Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s === CONT TestServe/Error_because_the_notifier_returns_an_error 327s time="2026-02-03T08:23:50Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:50Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37151" 327s time="2026-02-03T08:23:50Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:51Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:23:51Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:51Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:51Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:52Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 327s time="2026-02-03T08:23:52Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:52Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:52Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/Error_because_the_context_is_pre-cancelled 327s time="2026-02-03T08:23:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39063" 327s time="2026-02-03T08:23:52Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:52Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:52Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:52Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:23:52Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:52Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:23:52Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s === CONT TestServe/No_connection_because_there_are_no_certificates 327s time="2026-02-03T08:23:52Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:52Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36713" 327s time="2026-02-03T08:23:52Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:55Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:55Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:55Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 327s time="2026-02-03T08:23:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41913" 327s time="2026-02-03T08:23:55Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:55Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:55Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:55Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:57Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36713" 327s time="2026-02-03T08:23:57Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates3137453797/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 327s time="2026-02-03T08:23:57Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:23:57Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:23:57Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:23:57Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/No_connection_because_the_port_file_has_port_0 327s time="2026-02-03T08:23:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43999" 327s time="2026-02-03T08:23:57Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:23:59Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:23:59Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:23:59Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:23:59Z" 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" 327s time="2026-02-03T08:23:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:00Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:00Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:00Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 327s time="2026-02-03T08:24:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36425" 327s time="2026-02-03T08:24:00Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:02Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:02Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:02Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:02Z" 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" 327s time="2026-02-03T08:24:02Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:03Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:03Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:03Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/Success_with_systemd_notifier_returning_true 327s time="2026-02-03T08:24:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33557" 327s time="2026-02-03T08:24:03Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:05Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:05Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:05Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:05Z" 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_exist826742931/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist826742931/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 327s time="2026-02-03T08:24:05Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:06Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:06Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:06Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 327s time="2026-02-03T08:24:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46565" 327s time="2026-02-03T08:24:06Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:07Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:24:07Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:07Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:07Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:07Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:24:10Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33557" 327s time="2026-02-03T08:24:10Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:24:10Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:24:10Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:24:10Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:24:11Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:11Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:11Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:13Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:24:13Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:24:13Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:24:13Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:24:13Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:13Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:13Z" level=debug msg="All connections have now ended." 327s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 327s time="2026-02-03T08:24:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46801" 327s time="2026-02-03T08:24:13Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:14Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:14Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:14Z" level=warning msg="Daemon: disconnected from Windows host" 327s time="2026-02-03T08:24:14Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:14Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:24:14Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:24:14Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:24:14Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:14Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:14Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s === CONT TestServe/No_connection_because_there_is_no_server 327s time="2026-02-03T08:24:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36341" 327s time="2026-02-03T08:24:14Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:17Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:17Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:17Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:20Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:20Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:20Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:20Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46801" 327s time="2026-02-03T08:24:20Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_347046055/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 327s time="2026-02-03T08:24:20Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:20Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:20Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:20Z" level=debug msg="All connections have now ended." 327s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 327s time="2026-02-03T08:24:20Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:22Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36341" 327s time="2026-02-03T08:24:22Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:24:22Z" 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:36341: connect: connection refused\"" 327s time="2026-02-03T08:24:22Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:23Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:23Z" level=info msg="Waiting for active requests to close." 327s time="2026-02-03T08:24:23Z" level=debug msg="All connections have now ended." 327s --- PASS: TestServe (0.00s) 327s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (3.41s) 327s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (3.31s) 327s --- PASS: TestServe/Success_with_a_broken_Landscape_config (6.34s) 327s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (1.94s) 327s --- PASS: TestServe/Success (5.14s) 327s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (2.86s) 327s --- PASS: TestServe/No_connection_because_there_are_no_certificates (4.90s) 327s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (4.99s) 327s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (5.89s) 327s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (6.13s) 327s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (7.11s) 327s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (11.76s) 327s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (7.34s) 327s --- PASS: TestServe/No_connection_because_there_is_no_server (8.36s) 327s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 327s time="2026-02-03T08:24:23Z" level=debug msg="Building new daemon" 327s time="2026-02-03T08:24:26Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39933" 327s time="2026-02-03T08:24:26Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:24:26Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:26Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:26Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:26Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:24:28Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38865" 327s time="2026-02-03T08:24:28Z" level=debug msg="Ready state sent to systemd" 327s time="2026-02-03T08:24:28Z" level=debug msg="Checking cloud-init status" 327s time="2026-02-03T08:24:28Z" level=debug msg="No Landscape configuration detected: nothing to do" 327s time="2026-02-03T08:24:28Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:28Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:24:28Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39933" 327s time="2026-02-03T08:24:28Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:24:28Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:24:28Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:24:28Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:24:30Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38865" 327s time="2026-02-03T08:24:30Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:24:30Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:24:30Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:24:30Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:24:31Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:24:31Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:24:31Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:24:31Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:24:32Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:24:32Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:24:32Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:24:32Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:24:33Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:33Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 327s time="2026-02-03T08:24:33Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:24:33Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:24:33Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:33Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44825" 327s time="2026-02-03T08:24:34Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:24:34Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:24:35Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44825" 327s time="2026-02-03T08:24:35Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:24:35Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:24:35Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:24:35Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:24:37Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:24:37Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:24:37Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:24:37Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:24:37Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 327s time="2026-02-03T08:24:37Z" level=info msg="Reconnecting to Windows host in 2 seconds" 327s time="2026-02-03T08:24:37Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:24:37Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:24:37Z" level=info msg="Stopping active requests." 327s time="2026-02-03T08:24:37Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s time="2026-02-03T08:24:37Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:24:37Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:37Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:24:37Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:36Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 327s time="2026-02-03T08:25:36Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:25:36Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:25:36Z" 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_co1833534080/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_co1833534080/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 327s time="2026-02-03T08:25:36Z" level=info msg="Reconnecting to Windows host in 1 seconds" 327s time="2026-02-03T08:25:36Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:25:36Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:36Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:36Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37821" 327s time="2026-02-03T08:25:37Z" level=info msg="Daemon: connecting to Windows Agent from PID 6849" 327s time="2026-02-03T08:25:37Z" level=debug msg="Updated systemd status to \"Connecting\"" 327s time="2026-02-03T08:25:38Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37821" 327s time="2026-02-03T08:25:38Z" level=info msg="Daemon: completed connection to Windows Agent" 327s time="2026-02-03T08:25:38Z" level=debug msg="Updated systemd status to \"Connected\"" 327s time="2026-02-03T08:25:38Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 327s time="2026-02-03T08:25:38Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 327s time="2026-02-03T08:25:39Z" level=debug msg="Server: sent preface messages to all streams" 327s time="2026-02-03T08:25:39Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:25:39Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:25:39Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:25:39Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:39Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 327s time="2026-02-03T08:25:39Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:39Z" level=info msg="Reconnecting to Windows host in 2 seconds" 327s time="2026-02-03T08:25:39Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 327s time="2026-02-03T08:25:39Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:25:39Z" level=info msg="Stopping daemon requested." 327s time="2026-02-03T08:25:39Z" level=info msg="Stopping active requests." 327s time="2026-02-03T08:25:39Z" level=debug msg="All connections have now ended." 327s time="2026-02-03T08:25:39Z" level=debug msg="Updated systemd status to \"Stopped\"" 327s --- PASS: TestReconnection (0.00s) 327s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (8.59s) 327s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (14.43s) 327s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (79.03s) 327s PASS 327s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 136.780s 327s === RUN TestConnect 327s === PAUSE TestConnect 327s === RUN TestSendAndRecv 327s --- PASS: TestSendAndRecv (0.41s) 327s === RUN TestServe 327s === PAUSE TestServe 327s === RUN TestStop 327s === PAUSE TestStop 327s === RUN TestWithProMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithProMock (0.00s) 327s === RUN TestWithWslPathMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslPathMock (0.00s) 327s === RUN TestWithWslInfoMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslInfoMock (0.00s) 327s === RUN TestWithCmdExeMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithCmdExeMock (0.00s) 327s === CONT TestConnect 327s === RUN TestConnect/Success 327s === CONT TestStop 327s === PAUSE TestConnect/Success 327s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 327s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 327s === CONT TestConnect/Success 327s time="2026-02-03T08:23:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42517" 327s === CONT TestServe 327s time="2026-02-03T08:23:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35551" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: Connected ready" 327s time="2026-02-03T08:23:28Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 327s time="2026-02-03T08:23:34Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:34Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:34Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s --- PASS: TestServe (8.72s) 327s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 327s time="2026-02-03T08:23:38Z" 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: " 327s time="2026-02-03T08:23:38Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:38Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 327s time="2026-02-03T08:23:38Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 327s --- PASS: TestStop (13.01s) 327s --- PASS: TestConnect (0.00s) 327s --- PASS: TestConnect/Success (0.30s) 327s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.02s) 327s PASS 327s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 29.490s 327s === RUN TestNew 327s === PAUSE TestNew 327s === RUN TestInfo 327s === PAUSE TestInfo 327s === RUN TestWslDistroName 327s === PAUSE TestWslDistroName 327s === RUN TestUserProfileDir 327s === PAUSE TestUserProfileDir 327s === RUN TestProStatus 327s === PAUSE TestProStatus 327s === RUN TestProAttach 327s === PAUSE TestProAttach 327s === RUN TestProDetach 327s === PAUSE TestProDetach 327s === RUN TestLandscapeEnable 327s === PAUSE TestLandscapeEnable 327s === RUN TestWindowsHostAddress 327s === PAUSE TestWindowsHostAddress 327s === RUN TestLandscapeDisable 327s === PAUSE TestLandscapeDisable 327s === RUN TestEnsureValidLandscapeConfig 327s === PAUSE TestEnsureValidLandscapeConfig 327s === RUN TestRealBackend 327s === PAUSE TestRealBackend 327s === RUN TestWithProMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithProMock (0.00s) 327s === RUN TestWithLandscapeConfigMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithLandscapeConfigMock (0.00s) 327s === RUN TestWithWslPathMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslPathMock (0.00s) 327s === RUN TestWithWslInfoMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithWslInfoMock (0.00s) 327s === RUN TestWithCmdExeMock 327s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 327s --- SKIP: TestWithCmdExeMock (0.00s) 327s === CONT TestNew 327s === RUN TestNew/Return_a_new_system 327s === PAUSE TestNew/Return_a_new_system 327s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 327s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 327s === CONT TestNew/Return_a_new_system 327s === CONT TestRealBackend 327s --- PASS: TestRealBackend (0.00s) 327s === CONT TestEnsureValidLandscapeConfig 327s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 327s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 327s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 327s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 327s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 327s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 327s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 327s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 327s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 327s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 327s === CONT TestProAttach 327s === RUN TestProAttach/success 327s === PAUSE TestProAttach/success 327s === RUN TestProAttach/error_on_'pro_attach'_error 327s === PAUSE TestProAttach/error_on_'pro_attach'_error 327s === CONT TestProAttach/success 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 327s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 327s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 327s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 327s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 327s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 327s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 327s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 327s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 327s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 327s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 327s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 327s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 327s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 327s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 327s === CONT TestProStatus 327s === RUN TestProStatus/success_on_unattached_distro 327s === PAUSE TestProStatus/success_on_unattached_distro 327s === RUN TestProStatus/success_on_attached_distro 327s === PAUSE TestProStatus/success_on_attached_distro 327s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 327s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 327s === RUN TestProStatus/error_on_'pro_attach'_error 327s === PAUSE TestProStatus/error_on_'pro_attach'_error 327s === CONT TestUserProfileDir 327s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 327s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 327s === RUN TestUserProfileDir/Error_on_wslpath_error 327s === PAUSE TestUserProfileDir/Error_on_wslpath_error 327s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 327s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 327s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 327s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 327s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 327s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 327s === RUN TestUserProfileDir/Error_on_cmd.exe_error 327s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 327s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 327s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 327s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 327s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 327s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 327s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 327s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 327s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 327s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 327s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 327s === CONT TestWslDistroName 327s === RUN TestWslDistroName/Success_using_wslpath 327s === PAUSE TestWslDistroName/Success_using_wslpath 327s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 327s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 327s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 327s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 327s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 327s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 327s === CONT TestInfo 327s === RUN TestInfo/Success 327s === PAUSE TestInfo/Success 327s === RUN TestInfo/Error_when_WslDistroName_fails 327s === PAUSE TestInfo/Error_when_WslDistroName_fails 327s === RUN TestInfo/Error_when_pro_status_command_fails 327s === PAUSE TestInfo/Error_when_pro_status_command_fails 327s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 327s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 327s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 327s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 327s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 327s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 327s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 327s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 327s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 327s --- PASS: TestNew (0.00s) 327s --- PASS: TestNew/Return_a_new_system (0.00s) 327s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 327s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 327s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_due_whitespace_changes 327s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 327s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 327s === NAME TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 327s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 327s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 327s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 327s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 327s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 327s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 327s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 327s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 327s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 327s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 327s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 327s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 327s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 327s === CONT TestLandscapeEnable 327s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 327s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 327s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 327s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 327s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 327s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 327s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 327s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 327s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 327s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 327s === RUN TestLandscapeEnable/Regular_with_additional_keys 327s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 327s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 327s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 327s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 327s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 327s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 327s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 327s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 327s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 327s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 327s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 327s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 327s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 327s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 327s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 327s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 327s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 327s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 327s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 327s === CONT TestLandscapeDisable 327s === RUN TestLandscapeDisable/Success 327s === PAUSE TestLandscapeDisable/Success 327s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 327s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 327s === CONT TestWindowsHostAddress 327s === RUN TestWindowsHostAddress/Without_NAT 327s === PAUSE TestWindowsHostAddress/Without_NAT 327s === RUN TestWindowsHostAddress/With_NAT 327s === PAUSE TestWindowsHostAddress/With_NAT 327s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 327s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 327s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 327s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 327s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 327s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 327s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 327s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 327s === CONT TestProAttach/error_on_'pro_attach'_error 327s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 327s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 327s === CONT TestProDetach 327s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 327s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 327s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 327s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 327s === RUN TestProDetach/success_on_unattached_distro 327s === PAUSE TestProDetach/success_on_unattached_distro 327s === RUN TestProDetach/success_on_attached_distro 327s === PAUSE TestProDetach/success_on_attached_distro 327s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 327s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 327s === CONT TestProStatus/success_on_unattached_distro 327s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 327s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (2.64s) 327s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes (2.02s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (2.49s) 327s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (2.18s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (2.19s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (0.02s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.02s) 327s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (0.01s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (4.61s) 327s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (0.04s) 327s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (4.53s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (2.50s) 327s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (5.08s) 327s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (4.51s) 327s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (4.88s) 327s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (0.01s) 327s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (4.82s) 327s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (2.22s) 327s --- PASS: TestProAttach (0.00s) 327s --- PASS: TestProAttach/success (2.58s) 327s --- PASS: TestProAttach/error_on_'pro_attach'_error (2.35s) 327s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 327s === CONT TestProStatus/error_on_'pro_attach'_error 327s === CONT TestProStatus/success_on_attached_distro 327s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 327s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 327s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 327s --- PASS: TestProStatus (0.00s) 327s --- PASS: TestProStatus/success_on_unattached_distro (2.10s) 327s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (2.26s) 327s --- PASS: TestProStatus/error_on_'pro_attach'_error (2.60s) 327s --- PASS: TestProStatus/success_on_attached_distro (2.67s) 327s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 327s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 327s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 327s === CONT TestUserProfileDir/Error_on_cmd.exe_error 327s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 327s system_test.go:245: Removing default proc/mounts 327s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 327s system_test.go:245: Removing default proc/mounts 327s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 327s === CONT TestUserProfileDir/Error_on_wslpath_error 327s === CONT TestWslDistroName/Success_using_wslpath 327s --- PASS: TestUserProfileDir (0.00s) 327s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 327s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (5.02s) 327s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (4.57s) 327s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.90s) 327s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.96s) 327s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (1.71s) 327s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 327s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 327s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.65s) 327s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (3.10s) 327s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.64s) 327s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 327s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 327s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 327s === CONT TestInfo/Success 327s --- PASS: TestWslDistroName (0.00s) 327s --- PASS: TestWslDistroName/Success_using_wslpath (1.10s) 327s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 327s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.20s) 327s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.10s) 327s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 327s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 327s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 327s === CONT TestInfo/Error_when_pro_status_command_fails 327s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 327s === CONT TestInfo/Error_when_WslDistroName_fails 327s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 327s --- PASS: TestInfo (0.00s) 327s --- PASS: TestInfo/Success (1.16s) 327s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.15s) 327s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.15s) 327s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.19s) 327s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.17s) 327s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.13s) 327s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.13s) 327s === CONT TestLandscapeDisable/Success 327s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 327s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 327s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 327s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 327s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 327s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 327s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 327s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 327s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 327s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 327s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 327s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 327s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 327s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 327s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 327s === CONT TestLandscapeEnable/Regular_with_additional_keys 327s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 327s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 327s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 327s === NAME TestLandscapeEnable/Regular_with_additional_keys 327s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 327s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 327s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 327s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 327s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 327s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 327s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 327s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 327s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 327s --- PASS: TestLandscapeDisable (0.00s) 327s --- PASS: TestLandscapeDisable/Success (1.13s) 327s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.11s) 327s === CONT TestWindowsHostAddress/Without_NAT 327s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 327s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 327s --- PASS: TestLandscapeEnable (0.00s) 327s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (1.13s) 327s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (1.09s) 327s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 327s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.00s) 327s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (2.21s) 327s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (2.22s) 327s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (1.12s) 327s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (1.11s) 327s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (2.28s) 327s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (2.22s) 327s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (2.36s) 327s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.11s) 327s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (1.07s) 327s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (2.40s) 327s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (1.24s) 327s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (2.39s) 327s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 327s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 327s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 327s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 327s === CONT TestWindowsHostAddress/With_NAT 327s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 327s --- PASS: TestWindowsHostAddress (0.00s) 327s --- PASS: TestWindowsHostAddress/Without_NAT (1.15s) 327s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.15s) 327s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.14s) 327s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.14s) 327s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.12s) 327s --- PASS: TestWindowsHostAddress/With_NAT (1.15s) 327s === CONT TestProDetach/success_on_attached_distro 327s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 327s === CONT TestProDetach/success_on_unattached_distro 327s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 327s --- PASS: TestProDetach (0.00s) 327s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.13s) 327s --- PASS: TestProDetach/success_on_attached_distro (1.13s) 327s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.15s) 327s --- PASS: TestProDetach/success_on_unattached_distro (1.12s) 327s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (0.77s) 327s PASS 327s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 67.280s 327s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 327s autopkgtest [08:25:40]: test command1: -----------------------] 327s command1 PASS 327s autopkgtest [08:25:40]: test command1: - - - - - - - - - - results - - - - - - - - - - 328s autopkgtest [08:25:41]: @@@@@@@@@@@@@@@@@@@@ summary 328s command1 PASS