0s autopkgtest [19:04:09]: starting date and time: 2026-02-04 19:04:09+0000 0s autopkgtest [19:04:09]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [19:04:09]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.92uupmu4/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-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-23.secgroup --name adt-resolute-amd64-wsl-pro-service-20260204-190409-juju-7f2275-prod-proposed-migration-environment-2-e9068408-0840-45eb-bbd4-ebfebf09031f --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-amd64-wsl-pro-service-20260204-190409-juju-7f2275-prod-proposed-migration-environment-2-e9068408-0840-45eb-bbd4-ebfebf09031f from image adt/ubuntu-resolute-amd64-server-20260204.img (UUID fedf54b4-458b-493e-8072-6425c19717b4)... 84s autopkgtest [19:05:33]: testbed dpkg architecture: amd64 84s autopkgtest [19:05:33]: testbed apt version: 3.1.14 84s autopkgtest [19:05:33]: @@@@@@@@@@@@@@@@@@@@ test bed setup 84s autopkgtest [19:05:33]: testbed release detected to be: None 85s autopkgtest [19:05:34]: updating testbed package index (apt update) 85s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 85s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 85s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 85s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 85s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 85s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [36.0 kB] 85s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1787 kB] 85s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [231 kB] 85s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [213 kB] 85s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [282 kB] 85s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [7276 B] 85s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3692 B] 85s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [32.9 kB] 86s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 86s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [1553 kB] 86s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [577 kB] 86s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [43.2 kB] 86s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4824 B] 86s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [29.5 kB] 86s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [1100 B] 86s Fetched 4931 kB in 1s (5006 kB/s) 87s Reading package lists... 87s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 88s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 88s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 88s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 88s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Calculating upgrade... 89s The following packages will be upgraded: 89s python3-linkify-it 89s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 19.4 kB of archives. 89s After this operation, 0 B of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 89s dpkg-preconfigure: unable to re-open stdin: No such file or directory 89s Fetched 19.4 kB in 0s (0 B/s) 89s (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 ... 83957 files and directories currently installed.) 89s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 89s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 89s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 89s autopkgtest [19:05:38]: upgrading testbed (apt dist-upgrade and autopurge) 89s Reading package lists... 89s Building dependency tree... 89s Reading state information... 90s Calculating upgrade... 90s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Reading package lists... 90s Building dependency tree... 90s Reading state information... 90s Solving dependencies... 90s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s autopkgtest [19:05:41]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 92s autopkgtest [19:05:41]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 93s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (dsc) [2088 B] 93s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main wsl-pro-service 0.1.18build1 (tar) [1792 kB] 93s gpgv: Signature made Mon Feb 2 20:53:03 2026 UTC 93s gpgv: using RSA key 92FE4F8B40CADC4ECCD60C1F5961FDBE9C619809 93s gpgv: Can't check signature: No public key 93s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.18build1.dsc: no acceptable signature found 94s autopkgtest [19:05:43]: testing package wsl-pro-service version 0.1.18build1 94s autopkgtest [19:05:43]: build not needed 95s autopkgtest [19:05:44]: test command1: preparing testbed 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Solving dependencies... 95s The following NEW packages will be installed: 95s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 95s cpp-15-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 95s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 95s g++-15-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-15 95s gcc-15-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.25-go 95s golang-1.25-src golang-go golang-src intltool-debian libarchive-zip-perl 95s libasan8 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl 95s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libhwasan0 libisl23 95s libitm1 liblsan0 libmpc3 libquadmath0 libstdc++-15-dev libtool libtsan2 95s libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 95s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 95s Need to get 120 MB of archives. 95s After this operation, 482 MB of additional disk space will be used. 95s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 m4 amd64 1.4.20-2 [217 kB] 95s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 autoconf all 2.72-3.1ubuntu1 [384 kB] 95s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 autotools-dev all 20240727.1 [43.4 kB] 95s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 automake all 1:1.18.1-3build1 [582 kB] 96s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 autopoint all 0.23.2-1 [620 kB] 96s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libc-dev-bin amd64 2.42-2ubuntu4 [23.3 kB] 96s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 linux-libc-dev amd64 6.18.0-9.9 [1860 kB] 97s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libcrypt-dev amd64 1:4.5.1-1 [122 kB] 97s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 rpcsvc-proto amd64 1.4.3-1build1 [68.3 kB] 97s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 libc6-dev amd64 2.42-2ubuntu4 [2207 kB] 97s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 libisl23 amd64 0.27-1build1 [691 kB] 97s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 libmpc3 amd64 1.3.1-2 [54.8 kB] 97s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15-x86-64-linux-gnu amd64 15.2.0-12ubuntu1 [12.9 MB] 98s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15 amd64 15.2.0-12ubuntu1 [1034 B] 98s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [5746 B] 98s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp amd64 4:15.2.0-4ubuntu1 [22.4 kB] 98s Get:17 http://ftpmaster.internal/ubuntu resolute/main amd64 libcc1-0 amd64 15.2.0-12ubuntu1 [47.4 kB] 98s Get:18 http://ftpmaster.internal/ubuntu resolute/main amd64 libgomp1 amd64 15.2.0-12ubuntu1 [151 kB] 98s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 libitm1 amd64 15.2.0-12ubuntu1 [29.5 kB] 98s Get:20 http://ftpmaster.internal/ubuntu resolute/main amd64 libasan8 amd64 15.2.0-12ubuntu1 [3070 kB] 98s Get:21 http://ftpmaster.internal/ubuntu resolute/main amd64 liblsan0 amd64 15.2.0-12ubuntu1 [1360 kB] 98s Get:22 http://ftpmaster.internal/ubuntu resolute/main amd64 libtsan2 amd64 15.2.0-12ubuntu1 [2757 kB] 98s Get:23 http://ftpmaster.internal/ubuntu resolute/main amd64 libubsan1 amd64 15.2.0-12ubuntu1 [1210 kB] 99s Get:24 http://ftpmaster.internal/ubuntu resolute/main amd64 libhwasan0 amd64 15.2.0-12ubuntu1 [1685 kB] 99s Get:25 http://ftpmaster.internal/ubuntu resolute/main amd64 libquadmath0 amd64 15.2.0-12ubuntu1 [153 kB] 99s Get:26 http://ftpmaster.internal/ubuntu resolute/main amd64 libgcc-15-dev amd64 15.2.0-12ubuntu1 [2866 kB] 99s Get:27 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15-x86-64-linux-gnu amd64 15.2.0-12ubuntu1 [25.4 MB] 100s Get:28 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15 amd64 15.2.0-12ubuntu1 [530 kB] 100s Get:29 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [1208 B] 100s Get:30 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc amd64 4:15.2.0-4ubuntu1 [5024 B] 100s Get:31 http://ftpmaster.internal/ubuntu resolute/main amd64 libstdc++-15-dev amd64 15.2.0-12ubuntu1 [2553 kB] 100s Get:32 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15-x86-64-linux-gnu amd64 15.2.0-12ubuntu1 [14.4 MB] 100s Get:33 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15 amd64 15.2.0-12ubuntu1 [25.3 kB] 100s Get:34 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [966 B] 100s Get:35 http://ftpmaster.internal/ubuntu resolute/main amd64 g++ amd64 4:15.2.0-4ubuntu1 [1100 B] 100s Get:36 http://ftpmaster.internal/ubuntu resolute/main amd64 build-essential amd64 12.12ubuntu2 [5256 B] 100s Get:37 http://ftpmaster.internal/ubuntu resolute/main amd64 libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 100s Get:38 http://ftpmaster.internal/ubuntu resolute/main amd64 libtool all 2.5.4-9 [169 kB] 100s Get:39 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-autoreconf all 21 [12.5 kB] 100s Get:40 http://ftpmaster.internal/ubuntu resolute/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 100s Get:41 http://ftpmaster.internal/ubuntu resolute/main amd64 libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 100s Get:42 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 100s Get:43 http://ftpmaster.internal/ubuntu resolute/main amd64 debugedit amd64 1:5.2-3build1 [48.8 kB] 100s Get:44 http://ftpmaster.internal/ubuntu resolute/main amd64 dwz amd64 0.16-2 [115 kB] 100s Get:45 http://ftpmaster.internal/ubuntu resolute/main amd64 gettext amd64 0.23.2-1 [1019 kB] 100s Get:46 http://ftpmaster.internal/ubuntu resolute/main amd64 intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 100s Get:47 http://ftpmaster.internal/ubuntu resolute/main amd64 po-debconf all 1.0.22 [215 kB] 100s Get:48 http://ftpmaster.internal/ubuntu resolute/main amd64 debhelper all 13.28ubuntu1 [916 kB] 100s Get:49 http://ftpmaster.internal/ubuntu resolute/universe amd64 dh-apport all 2.33.1-0ubuntu3 [20.2 kB] 100s Get:50 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 102s Get:51 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-1.25-go amd64 1.25.0-0ubuntu1 [18.0 MB] 109s Get:52 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-src all 2:1.25~1 [5206 B] 109s Get:53 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-go amd64 2:1.25~1 [44.1 kB] 109s Get:54 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-golang all 1.63build1 [25.0 kB] 109s Fetched 120 MB in 14s (8631 kB/s) 109s Selecting previously unselected package m4. 110s (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 ... 83957 files and directories currently installed.) 110s Preparing to unpack .../00-m4_1.4.20-2_amd64.deb ... 110s Unpacking m4 (1.4.20-2) ... 110s Selecting previously unselected package autoconf. 110s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 110s Unpacking autoconf (2.72-3.1ubuntu1) ... 110s Selecting previously unselected package autotools-dev. 110s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 110s Unpacking autotools-dev (20240727.1) ... 110s Selecting previously unselected package automake. 110s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ... 110s Unpacking automake (1:1.18.1-3build1) ... 110s Selecting previously unselected package autopoint. 110s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ... 110s Unpacking autopoint (0.23.2-1) ... 110s Selecting previously unselected package libc-dev-bin. 110s Preparing to unpack .../05-libc-dev-bin_2.42-2ubuntu4_amd64.deb ... 110s Unpacking libc-dev-bin (2.42-2ubuntu4) ... 110s Selecting previously unselected package linux-libc-dev:amd64. 110s Preparing to unpack .../06-linux-libc-dev_6.18.0-9.9_amd64.deb ... 110s Unpacking linux-libc-dev:amd64 (6.18.0-9.9) ... 110s Selecting previously unselected package libcrypt-dev:amd64. 110s Preparing to unpack .../07-libcrypt-dev_1%3a4.5.1-1_amd64.deb ... 110s Unpacking libcrypt-dev:amd64 (1:4.5.1-1) ... 110s Selecting previously unselected package rpcsvc-proto. 110s Preparing to unpack .../08-rpcsvc-proto_1.4.3-1build1_amd64.deb ... 110s Unpacking rpcsvc-proto (1.4.3-1build1) ... 110s Selecting previously unselected package libc6-dev:amd64. 110s Preparing to unpack .../09-libc6-dev_2.42-2ubuntu4_amd64.deb ... 110s Unpacking libc6-dev:amd64 (2.42-2ubuntu4) ... 110s Selecting previously unselected package libisl23:amd64. 110s Preparing to unpack .../10-libisl23_0.27-1build1_amd64.deb ... 110s Unpacking libisl23:amd64 (0.27-1build1) ... 110s Selecting previously unselected package libmpc3:amd64. 110s Preparing to unpack .../11-libmpc3_1.3.1-2_amd64.deb ... 110s Unpacking libmpc3:amd64 (1.3.1-2) ... 110s Selecting previously unselected package cpp-15-x86-64-linux-gnu. 110s Preparing to unpack .../12-cpp-15-x86-64-linux-gnu_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking cpp-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package cpp-15. 110s Preparing to unpack .../13-cpp-15_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package cpp-x86-64-linux-gnu. 110s Preparing to unpack .../14-cpp-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 110s Unpacking cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 110s Selecting previously unselected package cpp. 110s Preparing to unpack .../15-cpp_4%3a15.2.0-4ubuntu1_amd64.deb ... 110s Unpacking cpp (4:15.2.0-4ubuntu1) ... 110s Selecting previously unselected package libcc1-0:amd64. 110s Preparing to unpack .../16-libcc1-0_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libcc1-0:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libgomp1:amd64. 110s Preparing to unpack .../17-libgomp1_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libgomp1:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libitm1:amd64. 110s Preparing to unpack .../18-libitm1_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libitm1:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libasan8:amd64. 110s Preparing to unpack .../19-libasan8_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libasan8:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package liblsan0:amd64. 110s Preparing to unpack .../20-liblsan0_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking liblsan0:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libtsan2:amd64. 110s Preparing to unpack .../21-libtsan2_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libtsan2:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libubsan1:amd64. 110s Preparing to unpack .../22-libubsan1_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libubsan1:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libhwasan0:amd64. 110s Preparing to unpack .../23-libhwasan0_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libhwasan0:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libquadmath0:amd64. 110s Preparing to unpack .../24-libquadmath0_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libquadmath0:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package libgcc-15-dev:amd64. 110s Preparing to unpack .../25-libgcc-15-dev_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libgcc-15-dev:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package gcc-15-x86-64-linux-gnu. 110s Preparing to unpack .../26-gcc-15-x86-64-linux-gnu_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking gcc-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package gcc-15. 110s Preparing to unpack .../27-gcc-15_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package gcc-x86-64-linux-gnu. 110s Preparing to unpack .../28-gcc-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 110s Unpacking gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 110s Selecting previously unselected package gcc. 110s Preparing to unpack .../29-gcc_4%3a15.2.0-4ubuntu1_amd64.deb ... 110s Unpacking gcc (4:15.2.0-4ubuntu1) ... 110s Selecting previously unselected package libstdc++-15-dev:amd64. 110s Preparing to unpack .../30-libstdc++-15-dev_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking libstdc++-15-dev:amd64 (15.2.0-12ubuntu1) ... 110s Selecting previously unselected package g++-15-x86-64-linux-gnu. 110s Preparing to unpack .../31-g++-15-x86-64-linux-gnu_15.2.0-12ubuntu1_amd64.deb ... 110s Unpacking g++-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 111s Selecting previously unselected package g++-15. 111s Preparing to unpack .../32-g++-15_15.2.0-12ubuntu1_amd64.deb ... 111s Unpacking g++-15 (15.2.0-12ubuntu1) ... 111s Selecting previously unselected package g++-x86-64-linux-gnu. 111s Preparing to unpack .../33-g++-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 111s Unpacking g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 111s Selecting previously unselected package g++. 111s Preparing to unpack .../34-g++_4%3a15.2.0-4ubuntu1_amd64.deb ... 111s Unpacking g++ (4:15.2.0-4ubuntu1) ... 111s Selecting previously unselected package build-essential. 111s Preparing to unpack .../35-build-essential_12.12ubuntu2_amd64.deb ... 111s Unpacking build-essential (12.12ubuntu2) ... 111s Selecting previously unselected package libdebhelper-perl. 111s Preparing to unpack .../36-libdebhelper-perl_13.28ubuntu1_all.deb ... 111s Unpacking libdebhelper-perl (13.28ubuntu1) ... 111s Selecting previously unselected package libtool. 111s Preparing to unpack .../37-libtool_2.5.4-9_all.deb ... 111s Unpacking libtool (2.5.4-9) ... 111s Selecting previously unselected package dh-autoreconf. 111s Preparing to unpack .../38-dh-autoreconf_21_all.deb ... 111s Unpacking dh-autoreconf (21) ... 111s Selecting previously unselected package libarchive-zip-perl. 111s Preparing to unpack .../39-libarchive-zip-perl_1.68-1_all.deb ... 111s Unpacking libarchive-zip-perl (1.68-1) ... 111s Selecting previously unselected package libfile-stripnondeterminism-perl. 111s Preparing to unpack .../40-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 111s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 111s Selecting previously unselected package dh-strip-nondeterminism. 111s Preparing to unpack .../41-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 111s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 111s Selecting previously unselected package debugedit. 111s Preparing to unpack .../42-debugedit_1%3a5.2-3build1_amd64.deb ... 111s Unpacking debugedit (1:5.2-3build1) ... 111s Selecting previously unselected package dwz. 111s Preparing to unpack .../43-dwz_0.16-2_amd64.deb ... 111s Unpacking dwz (0.16-2) ... 111s Selecting previously unselected package gettext. 111s Preparing to unpack .../44-gettext_0.23.2-1_amd64.deb ... 111s Unpacking gettext (0.23.2-1) ... 111s Selecting previously unselected package intltool-debian. 111s Preparing to unpack .../45-intltool-debian_0.35.0+20060710.6build1_all.deb ... 111s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 111s Selecting previously unselected package po-debconf. 111s Preparing to unpack .../46-po-debconf_1.0.22_all.deb ... 111s Unpacking po-debconf (1.0.22) ... 111s Selecting previously unselected package debhelper. 111s Preparing to unpack .../47-debhelper_13.28ubuntu1_all.deb ... 111s Unpacking debhelper (13.28ubuntu1) ... 111s Selecting previously unselected package dh-apport. 111s Preparing to unpack .../48-dh-apport_2.33.1-0ubuntu3_all.deb ... 111s Unpacking dh-apport (2.33.1-0ubuntu3) ... 111s Selecting previously unselected package golang-1.25-src. 111s Preparing to unpack .../49-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 111s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 112s Selecting previously unselected package golang-1.25-go. 112s Preparing to unpack .../50-golang-1.25-go_1.25.0-0ubuntu1_amd64.deb ... 112s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 112s Selecting previously unselected package golang-src. 112s Preparing to unpack .../51-golang-src_2%3a1.25~1_all.deb ... 112s Unpacking golang-src (2:1.25~1) ... 112s Selecting previously unselected package golang-go:amd64. 112s Preparing to unpack .../52-golang-go_2%3a1.25~1_amd64.deb ... 112s Unpacking golang-go:amd64 (2:1.25~1) ... 112s Selecting previously unselected package dh-golang. 112s Preparing to unpack .../53-dh-golang_1.63build1_all.deb ... 112s Unpacking dh-golang (1.63build1) ... 112s Setting up libarchive-zip-perl (1.68-1) ... 112s Setting up libdebhelper-perl (13.28ubuntu1) ... 112s Setting up linux-libc-dev:amd64 (6.18.0-9.9) ... 112s Setting up m4 (1.4.20-2) ... 112s Setting up libgomp1:amd64 (15.2.0-12ubuntu1) ... 112s Setting up dh-apport (2.33.1-0ubuntu3) ... 112s Setting up autotools-dev (20240727.1) ... 112s Setting up rpcsvc-proto (1.4.3-1build1) ... 112s Setting up libquadmath0:amd64 (15.2.0-12ubuntu1) ... 112s Setting up libmpc3:amd64 (1.3.1-2) ... 112s Setting up autopoint (0.23.2-1) ... 112s Setting up autoconf (2.72-3.1ubuntu1) ... 112s Setting up libubsan1:amd64 (15.2.0-12ubuntu1) ... 112s Setting up dwz (0.16-2) ... 112s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 112s Setting up libhwasan0:amd64 (15.2.0-12ubuntu1) ... 112s Setting up libcrypt-dev:amd64 (1:4.5.1-1) ... 112s Setting up libasan8:amd64 (15.2.0-12ubuntu1) ... 112s Setting up debugedit (1:5.2-3build1) ... 112s Setting up libtsan2:amd64 (15.2.0-12ubuntu1) ... 112s Setting up libisl23:amd64 (0.27-1build1) ... 112s Setting up libc-dev-bin (2.42-2ubuntu4) ... 112s Setting up golang-src (2:1.25~1) ... 112s Setting up libcc1-0:amd64 (15.2.0-12ubuntu1) ... 112s Setting up liblsan0:amd64 (15.2.0-12ubuntu1) ... 112s Setting up libitm1:amd64 (15.2.0-12ubuntu1) ... 112s Setting up automake (1:1.18.1-3build1) ... 112s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 112s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 112s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 112s Setting up gettext (0.23.2-1) ... 112s Setting up libgcc-15-dev:amd64 (15.2.0-12ubuntu1) ... 112s Setting up golang-go:amd64 (2:1.25~1) ... 112s Setting up cpp-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 112s Setting up intltool-debian (0.35.0+20060710.6build1) ... 112s Setting up gcc-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 112s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 112s Setting up cpp-15 (15.2.0-12ubuntu1) ... 112s Setting up libc6-dev:amd64 (2.42-2ubuntu4) ... 112s Setting up cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 112s Setting up gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 112s Setting up po-debconf (1.0.22) ... 112s Setting up gcc-15 (15.2.0-12ubuntu1) ... 112s Setting up libstdc++-15-dev:amd64 (15.2.0-12ubuntu1) ... 112s Setting up cpp (4:15.2.0-4ubuntu1) ... 112s Setting up g++-15-x86-64-linux-gnu (15.2.0-12ubuntu1) ... 112s Setting up libtool (2.5.4-9) ... 112s Setting up gcc (4:15.2.0-4ubuntu1) ... 112s Setting up dh-autoreconf (21) ... 112s Setting up g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 112s Setting up g++-15 (15.2.0-12ubuntu1) ... 112s Setting up debhelper (13.28ubuntu1) ... 112s Setting up g++ (4:15.2.0-4ubuntu1) ... 112s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 112s Setting up build-essential (12.12ubuntu2) ... 112s Setting up dh-golang (1.63build1) ... 112s Processing triggers for man-db (2.13.1-1) ... 113s Processing triggers for install-info (7.2-5) ... 113s Processing triggers for libc-bin (2.42-2ubuntu4) ... 113s autopkgtest [19:06:02]: test command1: ./debian/tests/test 113s autopkgtest [19:06:02]: test command1: [----------------------- 138s === RUN TestRunSignal 138s === RUN TestRunSignal/Send_SIGINT_exits 138s INFO Starting WSL Pro Service version Dev 138s === RUN TestRunSignal/Send_SIGTERM_exits 138s INFO Starting WSL Pro Service version Dev 138s --- PASS: TestRunSignal (0.20s) 138s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 138s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 138s === RUN TestRun 138s === PAUSE TestRun 138s === CONT TestRun 138s === RUN TestRun/Run_and_exit_successfully 138s === PAUSE TestRun/Run_and_exit_successfully 138s === RUN TestRun/Run_and_return_error 138s === PAUSE TestRun/Run_and_return_error 138s === RUN TestRun/Run_and_return_usage_error 138s === PAUSE TestRun/Run_and_return_usage_error 138s === RUN TestRun/Run_and_usage_error_only_does_not_fail 138s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 138s === CONT TestRun/Run_and_exit_successfully 138s === CONT TestRun/Run_and_return_usage_error 138s INFO Starting WSL Pro Service version Dev 138s INFO Starting WSL Pro Service version Dev 138s === CONT TestRun/Run_and_usage_error_only_does_not_fail 138s INFO Starting WSL Pro Service version Dev 138s ERROR context.Background Error requested 138s === CONT TestRun/Run_and_return_error 138s INFO Starting WSL Pro Service version Dev 138s ERROR context.Background Error requested 138s --- PASS: TestRun (0.00s) 138s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 138s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 138s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 138s --- PASS: TestRun/Run_and_return_error (0.10s) 138s PASS 138s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.406s 147s === RUN TestHelp 147s --- PASS: TestHelp (0.00s) 147s === RUN TestCompletion 147s --- PASS: TestCompletion (0.00s) 147s === RUN TestVersion 147s --- PASS: TestVersion (0.00s) 147s === RUN TestConfigBadArg 147s --- PASS: TestConfigBadArg (0.00s) 147s === RUN TestConfigArg 147s --- PASS: TestConfigArg (0.00s) 147s === RUN TestConfigAutoDetect 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 147s --- PASS: TestConfigAutoDetect (0.00s) 147s === RUN TestNoUsageError 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 147s --- PASS: TestNoUsageError (0.00s) 147s === RUN TestUsageError 147s === PAUSE TestUsageError 147s === RUN TestCanQuitWhenExecute 147s === PAUSE TestCanQuitWhenExecute 147s === RUN TestCanQuitTwice 147s === PAUSE TestCanQuitTwice 147s === RUN TestAppCanQuitWithoutExecute 147s === PAUSE TestAppCanQuitWithoutExecute 147s === RUN TestAppRunFailsOnComponentsCreationAndQuit 147s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 147s === RUN TestAppGetRootCmd 147s === PAUSE TestAppGetRootCmd 147s === RUN TestWithProMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithProMock (0.00s) 147s === RUN TestWithWslPathMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithWslPathMock (0.00s) 147s === RUN TestWithWslInfoMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithWslInfoMock (0.00s) 147s === RUN TestWithCmdExeMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithCmdExeMock (0.00s) 147s === CONT TestUsageError 147s === CONT TestAppCanQuitWithoutExecute 147s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 147s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 147s === CONT TestAppGetRootCmd 147s --- PASS: TestUsageError (0.00s) 147s === CONT TestCanQuitTwice 147s --- PASS: TestAppGetRootCmd (0.00s) 147s === CONT TestAppRunFailsOnComponentsCreationAndQuit 147s time="2026-02-04T19:06:26Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45601" 147s time="2026-02-04T19:06:26Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build313770491/b386/wsl-pro-service.yaml" 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:74 New() Building new daemon" 147s time="2026-02-04T19:06:26Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38689" 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 147s time="2026-02-04T19:06:26Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:74 New() Building new daemon" 147s time="2026-02-04T19:06:29Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 147s time="2026-02-04T19:06:29Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 147s time="2026-02-04T19:06:29Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 6253" 147s time="2026-02-04T19:06:29Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:29Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:30Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 147s time="2026-02-04T19:06:30Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 147s time="2026-02-04T19:06:30Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 6253" 147s time="2026-02-04T19:06:30Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:45601" 147s time="2026-02-04T19:06:30Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 147s time="2026-02-04T19:06:30Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 147s time="2026-02-04T19:06:30Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 147s time="2026-02-04T19:06:31Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:31Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:31Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s === CONT TestCanQuitWhenExecute 147s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (4.72s) 147s time="2026-02-04T19:06:31Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45697" 147s time="2026-02-04T19:06:31Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build313770491/b386/wsl-pro-service.yaml" 147s time="2026-02-04T19:06:31Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 147s time="2026-02-04T19:06:31Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:74 New() Building new daemon" 147s time="2026-02-04T19:06:32Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 147s time="2026-02-04T19:06:32Z" level=warning msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:196 (*Daemon).serveOnce() Daemon: disconnected from Windows host" 147s time="2026-02-04T19:06:32Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 147s time="2026-02-04T19:06:32Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s time="2026-02-04T19:06:32Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:32Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:32Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s time="2026-02-04T19:06:32Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:32Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:32Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s time="2026-02-04T19:06:32Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s time="2026-02-04T19:06:32Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s time="2026-02-04T19:06:32Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s --- PASS: TestCanQuitTwice (5.74s) 147s time="2026-02-04T19:06:33Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 147s time="2026-02-04T19:06:33Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 147s time="2026-02-04T19:06:33Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 6253" 147s time="2026-02-04T19:06:34Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:45697" 147s time="2026-02-04T19:06:34Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 147s time="2026-02-04T19:06:34Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 147s time="2026-02-04T19:06:34Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 147s time="2026-02-04T19:06:35Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s time="2026-02-04T19:06:35Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:35Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:35Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s time="2026-02-04T19:06:35Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.qmUC0R/build.VsA/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 147s time="2026-02-04T19:06:35Z" level=warning msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:194 (*Daemon).serveOnce() Daemon: disconnected from Windows host: could not serve: could not send first Connnected message: EOF" 147s time="2026-02-04T19:06:35Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 147s time="2026-02-04T19:06:35Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s time="2026-02-04T19:06:35Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 147s time="2026-02-04T19:06:35Z" level=info msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 147s time="2026-02-04T19:06:35Z" level=debug msg="/tmp/autopkgtest.qmUC0R/build.VsA/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 147s --- PASS: TestCanQuitWhenExecute (4.23s) 147s PASS 147s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 8.968s 147s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 147s === RUN TestApplyProToken 147s === PAUSE TestApplyProToken 147s === RUN TestApplyLandscapeConfig 147s === PAUSE TestApplyLandscapeConfig 147s === RUN TestWithProMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithProMock (0.00s) 147s === RUN TestWithLandscapeConfigMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithLandscapeConfigMock (0.00s) 147s === RUN TestWithWslPathMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithWslPathMock (0.00s) 147s === RUN TestWithWslInfoMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithWslInfoMock (0.00s) 147s === RUN TestWithCmdExeMock 147s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 147s --- SKIP: TestWithCmdExeMock (0.00s) 147s === CONT TestApplyProToken 147s === RUN TestApplyProToken/Success_detaching 147s === PAUSE TestApplyProToken/Success_detaching 147s === RUN TestApplyProToken/Error_calling_pro_detach 147s === PAUSE TestApplyProToken/Error_calling_pro_detach 147s === RUN TestApplyProToken/Error_calling_pro_attach 147s === PAUSE TestApplyProToken/Error_calling_pro_attach 147s === CONT TestApplyLandscapeConfig 147s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 147s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 147s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 147s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 147s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 147s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 147s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 147s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 147s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 147s time="2026-02-04T19:06:27Z" level=info msg="ApplyLandscapeConfig: received config: registering" 147s === RUN TestApplyProToken/Success_attaching 147s === PAUSE TestApplyProToken/Success_attaching 147s === CONT TestApplyProToken/Success_detaching 147s time="2026-02-04T19:06:27Z" level=info msg="ApplyProToken: Received empty token: detaching" 147s === CONT TestApplyProToken/Success_attaching 147s time="2026-02-04T19:06:28Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 147s === CONT TestApplyProToken/Error_calling_pro_attach 147s time="2026-02-04T19:06:29Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 147s === CONT TestApplyProToken/Error_calling_pro_detach 147s time="2026-02-04T19:06:31Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 147s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 147s time="2026-02-04T19:06:31Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 147s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 147s --- PASS: TestApplyProToken (0.00s) 147s --- PASS: TestApplyProToken/Success_detaching (1.22s) 147s --- PASS: TestApplyProToken/Success_attaching (2.41s) 147s --- PASS: TestApplyProToken/Error_calling_pro_attach (2.58s) 147s --- PASS: TestApplyProToken/Error_calling_pro_detach (1.25s) 147s time="2026-02-04T19:06:32Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 147s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 147s time="2026-02-04T19:06:32Z" level=info msg="ApplyLandscapeConfig: received config: registering" 147s --- PASS: TestApplyLandscapeConfig (0.00s) 147s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (1.40s) 147s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (1.05s) 147s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (1.02s) 147s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (0.95s) 147s PASS 147s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 5.981s 147s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 226s === RUN TestNew 226s === PAUSE TestNew 226s === RUN TestServe 226s === PAUSE TestServe 226s === RUN TestServeAndQuit 226s === PAUSE TestServeAndQuit 226s === RUN TestRetryLogic 226s === PAUSE TestRetryLogic 226s === RUN TestReconnection 226s === PAUSE TestReconnection 226s === RUN TestWithProMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithProMock (0.00s) 226s === RUN TestWithWslPathMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslPathMock (0.00s) 226s === RUN TestWithWslInfoMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslInfoMock (0.00s) 226s === RUN TestWithCmdExeMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithCmdExeMock (0.00s) 226s === CONT TestNew 226s === RUN TestNew/Success 226s === PAUSE TestNew/Success 226s === RUN TestNew/Error_when_WslPath_returns_error 226s === PAUSE TestNew/Error_when_WslPath_returns_error 226s === CONT TestNew/Success 226s === CONT TestServeAndQuit 226s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 226s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 226s === RUN TestServeAndQuit/Success_with_graceful_quit 226s === PAUSE TestServeAndQuit/Success_with_graceful_quit 226s === RUN TestServeAndQuit/Success_with_forceful_quit 226s === PAUSE TestServeAndQuit/Success_with_forceful_quit 226s === RUN TestServeAndQuit/Success_with_double_quit 226s === PAUSE TestServeAndQuit/Success_with_double_quit 226s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 226s time="2026-02-04T19:06:34Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39473" 226s time="2026-02-04T19:06:34Z" level=debug msg="Building new daemon" 226s === CONT TestReconnection 226s === RUN TestReconnection/Success_connecting_after_failing_to_connect 226s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 226s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 226s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 226s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 226s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 226s === CONT TestRetryLogic 226s === RUN TestRetryLogic/Without_retries 226s === PAUSE TestRetryLogic/Without_retries 226s === RUN TestRetryLogic/With_the_context_pre-cancelled 226s === PAUSE TestRetryLogic/With_the_context_pre-cancelled 226s === RUN TestRetryLogic/With_the_context_cancelled_while_waiting 226s === PAUSE TestRetryLogic/With_the_context_cancelled_while_waiting 226s === RUN TestRetryLogic/When_max_attempts_are_exhausted 226s === PAUSE TestRetryLogic/When_max_attempts_are_exhausted 226s === RUN TestRetryLogic/Error_only_when_action_errors 226s === PAUSE TestRetryLogic/Error_only_when_action_errors 226s === CONT TestServeAndQuit/Success_with_double_quit 226s time="2026-02-04T19:06:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44161" 226s time="2026-02-04T19:06:36Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:36Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:36Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:36Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:36Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:36Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s === CONT TestServeAndQuit/Success_with_forceful_quit 226s time="2026-02-04T19:06:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32949" 226s time="2026-02-04T19:06:36Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:38Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:38Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:38Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:38Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:38Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:39Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:39Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:39Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:39Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:39Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:39Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44161" 226s time="2026-02-04T19:06:39Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:39Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:39Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:39Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:39Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:32949" 226s time="2026-02-04T19:06:39Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:39Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:39Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:39Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:40Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:40Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:40Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:41Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:41Z" level=info msg="Stopping active requests." 226s time="2026-02-04T19:06:41Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:06:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:41Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:41Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:41Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:41Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:41Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:06:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:41Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:41Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:41Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestServeAndQuit/Success_with_graceful_quit 226s time="2026-02-04T19:06:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33097" 226s time="2026-02-04T19:06:41Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:41Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:41Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:41Z" level=debug msg="All connections have now ended." 226s === CONT TestServe 226s === RUN TestServe/No_connection_because_the_port_file_is_empty 226s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 226s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 226s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 226s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 226s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 226s === RUN TestServe/No_connection_because_there_are_no_certificates 226s === PAUSE TestServe/No_connection_because_there_are_no_certificates 226s === RUN TestServe/Error_because_the_context_is_pre-cancelled 226s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 226s === RUN TestServe/Error_because_the_notifier_returns_an_error 226s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 226s === RUN TestServe/Success_with_a_broken_Landscape_config 226s === PAUSE TestServe/Success_with_a_broken_Landscape_config 226s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 226s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 226s === RUN TestServe/No_connection_because_the_port_file_has_port_0 226s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 226s === RUN TestServe/No_connection_because_there_is_no_server 226s === PAUSE TestServe/No_connection_because_there_is_no_server 226s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 226s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 226s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 226s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 226s === RUN TestServe/Success 226s === PAUSE TestServe/Success 226s === RUN TestServe/Success_with_systemd_notifier_returning_true 226s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 226s === CONT TestNew/Error_when_WslPath_returns_error 226s time="2026-02-04T19:06:41Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:42Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:42Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:42Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:42Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s --- PASS: TestNew (0.00s) 226s --- PASS: TestNew/Success (2.13s) 226s --- PASS: TestNew/Error_when_WslPath_returns_error (1.95s) 226s === CONT TestReconnection/Success_connecting_after_failing_to_connect 226s time="2026-02-04T19:06:43Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:43Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33097" 226s time="2026-02-04T19:06:43Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:43Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:43Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:43Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:44Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:44Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:44Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:44Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:44Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:44Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:44Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:44Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:44Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:44Z" 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_connect822120606/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect822120606/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 226s time="2026-02-04T19:06:44Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:44Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:44Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:44Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:44Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:06:44Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:44Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:44Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:44Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:44Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:44Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:44Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44931" 226s === CONT TestRetryLogic/Without_retries 226s --- PASS: TestServeAndQuit (0.00s) 226s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.14s) 226s --- PASS: TestServeAndQuit/Success_with_forceful_quit (4.36s) 226s --- PASS: TestServeAndQuit/Success_with_double_quit (4.44s) 226s --- PASS: TestServeAndQuit/Success_with_graceful_quit (3.38s) 226s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 226s time="2026-02-04T19:06:45Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:45Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:45Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:46Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44931" 226s time="2026-02-04T19:06:46Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:46Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:46Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:46Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44447" 226s time="2026-02-04T19:06:46Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:46Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:46Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:46Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:46Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:46Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:46Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:46Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:46Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:46Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 226s time="2026-02-04T19:06:46Z" level=info msg="Reconnecting to Windows host in 2 seconds" 226s time="2026-02-04T19:06:46Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:46Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:46Z" level=info msg="Stopping active requests." 226s time="2026-02-04T19:06:46Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:46Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:46Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:46Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:46Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 226s time="2026-02-04T19:06:46Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:47Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44447" 226s time="2026-02-04T19:06:47Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:47Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:47Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:47Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:47Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:47Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:47Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:47Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:48Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44177" 226s time="2026-02-04T19:06:48Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:48Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:48Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:48Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:48Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:48Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44177" 226s time="2026-02-04T19:06:48Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:48Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:48Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:48Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:49Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:49Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:49Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:49Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 226s time="2026-02-04T19:06:49Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:49Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33699" 226s time="2026-02-04T19:06:50Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:50Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:51Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33699" 226s time="2026-02-04T19:06:51Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:51Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:51Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:51Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:51Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:51Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:51Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:51Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:51Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:51Z" level=info msg="Stopping active requests." 226s time="2026-02-04T19:06:51Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:06:51Z" level=info msg="Reconnecting to Windows host in 2 seconds" 226s time="2026-02-04T19:06:51Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:51Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:51Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:51Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:51Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:51Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestRetryLogic/When_max_attempts_are_exhausted 226s === CONT TestRetryLogic/Error_only_when_action_errors 226s === CONT TestRetryLogic/With_the_context_cancelled_while_waiting 226s === CONT TestRetryLogic/With_the_context_pre-cancelled 226s --- PASS: TestRetryLogic (0.00s) 226s --- PASS: TestRetryLogic/Without_retries (0.70s) 226s --- PASS: TestRetryLogic/When_max_attempts_are_exhausted (0.42s) 226s --- PASS: TestRetryLogic/Error_only_when_action_errors (0.00s) 226s --- PASS: TestRetryLogic/With_the_context_cancelled_while_waiting (0.03s) 226s --- PASS: TestRetryLogic/With_the_context_pre-cancelled (0.00s) 226s === CONT TestServe/No_connection_because_the_port_file_is_empty 226s time="2026-02-04T19:06:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41335" 226s time="2026-02-04T19:06:52Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:52Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:52Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:52Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:52Z" 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" 226s time="2026-02-04T19:06:52Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:53Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:53Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:53Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 226s time="2026-02-04T19:06:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42599" 226s time="2026-02-04T19:06:53Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:54Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:54Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:54Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:54Z" 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_exist2407308635/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2407308635/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 226s time="2026-02-04T19:06:54Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:55Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:55Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:55Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/Success_with_systemd_notifier_returning_true 226s time="2026-02-04T19:06:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36277" 226s time="2026-02-04T19:06:55Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:06:56Z" level=debug msg="Ready state sent to systemd" 226s time="2026-02-04T19:06:56Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:06:56Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:06:56Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:06:56Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:06:57Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36277" 226s time="2026-02-04T19:06:57Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:06:57Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:06:57Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:06:57Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:06:57Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:06:57Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:57Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:57Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:59Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:06:59Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:06:59Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:06:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:06:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:06:59Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:06:59Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:06:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestServe/Success 226s time="2026-02-04T19:06:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42901" 226s time="2026-02-04T19:06:59Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:00Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:00Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:01Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42901" 226s time="2026-02-04T19:07:01Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:07:01Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:07:01Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:07:01Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:07:01Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:07:01Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:07:01Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:07:03Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:03Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:03Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:07:03Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:03Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:07:03Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:03Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:03Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 226s time="2026-02-04T19:07:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36771" 226s time="2026-02-04T19:07:03Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:05Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:05Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:05Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:06Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:06Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:06Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 226s time="2026-02-04T19:07:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44093" 226s time="2026-02-04T19:07:06Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:08Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:08Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:08Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:09Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44093" 226s time="2026-02-04T19:07:09Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_854156670/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 226s time="2026-02-04T19:07:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:09Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:09Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:09Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_there_is_no_server 226s time="2026-02-04T19:07:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34499" 226s time="2026-02-04T19:07:09Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:11Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:11Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:11Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:12Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34499" 226s time="2026-02-04T19:07:12Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:07:12Z" 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:34499: connect: connection refused\"" 226s time="2026-02-04T19:07:12Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:12Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:12Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:12Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_the_port_file_has_port_0 226s time="2026-02-04T19:07:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40685" 226s time="2026-02-04T19:07:12Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:14Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:14Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:14Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:14Z" 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" 226s time="2026-02-04T19:07:14Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:15Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:15Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:15Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/Error_because_the_context_is_pre-cancelled 226s time="2026-02-04T19:07:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35235" 226s time="2026-02-04T19:07:15Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:17Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:17Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:17Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/Success_with_a_broken_Landscape_config 226s time="2026-02-04T19:07:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39345" 226s time="2026-02-04T19:07:17Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:19Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:19Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: read /tmp/TestServeSuccess_with_a_broken_Landscape_config3848844817/001/etc/landscape/client.conf: is a directory" 226s time="2026-02-04T19:07:19Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:20Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39345" 226s time="2026-02-04T19:07:20Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:07:20Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:07:20Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:07:21Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:07:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:07:21Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:07:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:07:22Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:22Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:22Z" level=warning msg="Daemon: disconnected from Windows host" 226s time="2026-02-04T19:07:22Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:22Z" level=debug msg="All connections have now ended." 226s time="2026-02-04T19:07:22Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s === CONT TestServe/Error_because_the_notifier_returns_an_error 226s time="2026-02-04T19:07:22Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:22Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33103" 226s time="2026-02-04T19:07:22Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:24Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 226s time="2026-02-04T19:07:24Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:24Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:24Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 226s time="2026-02-04T19:07:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38711" 226s time="2026-02-04T19:07:24Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:26Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:26Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:26Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:26Z" 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" 226s time="2026-02-04T19:07:26Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:27Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:27Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:27Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_there_are_no_certificates 226s time="2026-02-04T19:07:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39521" 226s time="2026-02-04T19:07:27Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:29Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:29Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:29Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:30Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39521" 226s time="2026-02-04T19:07:30Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates2073952083/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 226s time="2026-02-04T19:07:30Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:30Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:30Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:30Z" level=debug msg="All connections have now ended." 226s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 226s time="2026-02-04T19:07:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36191" 226s time="2026-02-04T19:07:30Z" level=debug msg="Building new daemon" 226s time="2026-02-04T19:07:32Z" level=debug msg="Checking cloud-init status" 226s time="2026-02-04T19:07:32Z" level=debug msg="No Landscape configuration detected: nothing to do" 226s time="2026-02-04T19:07:32Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:32Z" 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" 226s time="2026-02-04T19:07:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:33Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:33Z" level=info msg="Waiting for active requests to close." 226s time="2026-02-04T19:07:33Z" level=debug msg="All connections have now ended." 226s --- PASS: TestServe (0.00s) 226s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (1.87s) 226s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (1.93s) 226s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (3.90s) 226s --- PASS: TestServe/Success (3.88s) 226s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (2.91s) 226s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (3.02s) 226s --- PASS: TestServe/No_connection_because_there_is_no_server (3.06s) 226s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (2.84s) 226s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (2.01s) 226s --- PASS: TestServe/Success_with_a_broken_Landscape_config (5.10s) 226s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (2.12s) 226s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (2.92s) 226s --- PASS: TestServe/No_connection_because_there_are_no_certificates (3.06s) 226s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (2.87s) 226s time="2026-02-04T19:07:53Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:53Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 226s time="2026-02-04T19:07:53Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:53Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:07:53Z" 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_co1737905805/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_co1737905805/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 226s time="2026-02-04T19:07:53Z" level=info msg="Reconnecting to Windows host in 1 seconds" 226s time="2026-02-04T19:07:53Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:07:53Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:53Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37773" 226s time="2026-02-04T19:07:54Z" level=info msg="Daemon: connecting to Windows Agent from PID 7307" 226s time="2026-02-04T19:07:54Z" level=debug msg="Updated systemd status to \"Connecting\"" 226s time="2026-02-04T19:07:54Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37773" 226s time="2026-02-04T19:07:54Z" level=info msg="Daemon: completed connection to Windows Agent" 226s time="2026-02-04T19:07:54Z" level=debug msg="Updated systemd status to \"Connected\"" 226s time="2026-02-04T19:07:54Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 226s time="2026-02-04T19:07:54Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 226s time="2026-02-04T19:07:54Z" level=debug msg="Server: sent preface messages to all streams" 226s time="2026-02-04T19:07:54Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:07:54Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:07:54Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:07:55Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 226s time="2026-02-04T19:07:55Z" level=info msg="Reconnecting to Windows host in 2 seconds" 226s time="2026-02-04T19:07:55Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 226s time="2026-02-04T19:07:55Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:55Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:55Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:07:55Z" level=info msg="Stopping daemon requested." 226s time="2026-02-04T19:07:55Z" level=info msg="Stopping active requests." 226s time="2026-02-04T19:07:55Z" level=debug msg="Updated systemd status to \"Stopped\"" 226s time="2026-02-04T19:07:55Z" level=debug msg="All connections have now ended." 226s --- PASS: TestReconnection (0.00s) 226s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (3.78s) 226s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (4.67s) 226s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (69.78s) 226s PASS 226s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 80.370s 226s === RUN TestConnect 226s === PAUSE TestConnect 226s === RUN TestSendAndRecv 226s --- PASS: TestSendAndRecv (0.41s) 226s === RUN TestServe 226s === PAUSE TestServe 226s === RUN TestStop 226s === PAUSE TestStop 226s === RUN TestWithProMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithProMock (0.00s) 226s === RUN TestWithWslPathMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslPathMock (0.00s) 226s === RUN TestWithWslInfoMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslInfoMock (0.00s) 226s === RUN TestWithCmdExeMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithCmdExeMock (0.00s) 226s === CONT TestConnect 226s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 226s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 226s === RUN TestConnect/Success 226s === PAUSE TestConnect/Success 226s === CONT TestStop 226s === CONT TestServe 226s time="2026-02-04T19:06:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34285" 226s time="2026-02-04T19:06:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40279" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: Connected ready" 226s time="2026-02-04T19:06:39Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 226s time="2026-02-04T19:06:42Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s --- PASS: TestServe (5.12s) 226s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 226s time="2026-02-04T19:06:42Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:42Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 226s time="2026-02-04T19:06:49Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 226s --- PASS: TestStop (12.02s) 226s === CONT TestConnect/Success 226s --- PASS: TestConnect (0.00s) 226s --- PASS: TestConnect/Success (0.30s) 226s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.01s) 226s PASS 226s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 25.545s 226s === RUN TestNew 226s === PAUSE TestNew 226s === RUN TestInfo 226s === PAUSE TestInfo 226s === RUN TestWslDistroName 226s === PAUSE TestWslDistroName 226s === RUN TestUserProfileDir 226s === PAUSE TestUserProfileDir 226s === RUN TestProStatus 226s === PAUSE TestProStatus 226s === RUN TestProAttach 226s === PAUSE TestProAttach 226s === RUN TestProDetach 226s === PAUSE TestProDetach 226s === RUN TestLandscapeEnable 226s === PAUSE TestLandscapeEnable 226s === RUN TestWindowsHostAddress 226s === PAUSE TestWindowsHostAddress 226s === RUN TestLandscapeDisable 226s === PAUSE TestLandscapeDisable 226s === RUN TestEnsureValidLandscapeConfig 226s === PAUSE TestEnsureValidLandscapeConfig 226s === RUN TestRealBackend 226s === PAUSE TestRealBackend 226s === RUN TestWithProMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithProMock (0.00s) 226s === RUN TestWithLandscapeConfigMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithLandscapeConfigMock (0.00s) 226s === RUN TestWithWslPathMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslPathMock (0.00s) 226s === RUN TestWithWslInfoMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithWslInfoMock (0.00s) 226s === RUN TestWithCmdExeMock 226s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 226s --- SKIP: TestWithCmdExeMock (0.00s) 226s === CONT TestNew 226s === RUN TestNew/Return_a_new_system 226s === PAUSE TestNew/Return_a_new_system 226s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 226s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 226s === CONT TestNew/Return_a_new_system 226s === CONT TestProDetach 226s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 226s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 226s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 226s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 226s === RUN TestProDetach/success_on_unattached_distro 226s === PAUSE TestProDetach/success_on_unattached_distro 226s === RUN TestProDetach/success_on_attached_distro 226s === PAUSE TestProDetach/success_on_attached_distro 226s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 226s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 226s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 226s === CONT TestProAttach 226s === RUN TestProAttach/success 226s === PAUSE TestProAttach/success 226s === RUN TestProAttach/error_on_'pro_attach'_error 226s === PAUSE TestProAttach/error_on_'pro_attach'_error 226s === CONT TestProAttach/success 226s === CONT TestProStatus 226s === RUN TestProStatus/success_on_unattached_distro 226s === PAUSE TestProStatus/success_on_unattached_distro 226s === RUN TestProStatus/success_on_attached_distro 226s === PAUSE TestProStatus/success_on_attached_distro 226s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 226s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 226s === RUN TestProStatus/error_on_'pro_attach'_error 226s === PAUSE TestProStatus/error_on_'pro_attach'_error 226s === CONT TestUserProfileDir 226s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 226s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 226s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 226s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 226s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 226s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 226s === RUN TestUserProfileDir/Error_on_cmd.exe_error 226s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 226s === RUN TestUserProfileDir/Error_on_wslpath_error 226s === PAUSE TestUserProfileDir/Error_on_wslpath_error 226s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 226s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 226s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 226s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 226s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 226s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 226s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 226s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 226s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 226s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 226s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 226s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 226s === CONT TestWslDistroName 226s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 226s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 226s === RUN TestWslDistroName/Success_using_wslpath 226s === PAUSE TestWslDistroName/Success_using_wslpath 226s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 226s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 226s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 226s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 226s === CONT TestInfo 226s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 226s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 226s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 226s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 226s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 226s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 226s === RUN TestInfo/Success 226s === PAUSE TestInfo/Success 226s === RUN TestInfo/Error_when_WslDistroName_fails 226s === PAUSE TestInfo/Error_when_WslDistroName_fails 226s === RUN TestInfo/Error_when_pro_status_command_fails 226s === PAUSE TestInfo/Error_when_pro_status_command_fails 226s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 226s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 226s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 226s --- PASS: TestNew (0.00s) 226s --- PASS: TestNew/Return_a_new_system (0.00s) 226s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 226s === CONT TestProAttach/error_on_'pro_attach'_error 226s === CONT TestLandscapeEnable 226s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 226s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 226s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 226s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 226s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 226s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 226s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 226s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 226s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 226s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 226s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 226s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 226s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 226s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 226s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 226s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 226s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 226s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 226s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 226s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 226s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 226s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 226s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 226s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 226s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 226s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 226s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 226s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 226s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 226s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 226s === RUN TestLandscapeEnable/Regular_with_additional_keys 226s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 226s === CONT TestRealBackend 226s --- PASS: TestRealBackend (0.00s) 226s === CONT TestEnsureValidLandscapeConfig 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 226s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 226s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 226s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 226s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 226s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 226s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 226s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 226s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 226s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 226s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 226s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 226s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 226s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 226s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 226s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 226s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 226s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 226s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 226s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 226s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 226s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 226s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 226s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 226s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 226s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 226s === CONT TestLandscapeDisable 226s === RUN TestLandscapeDisable/Success 226s === PAUSE TestLandscapeDisable/Success 226s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 226s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 226s === CONT TestWindowsHostAddress 226s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 226s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 226s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 226s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 226s === RUN TestWindowsHostAddress/Without_NAT 226s === PAUSE TestWindowsHostAddress/Without_NAT 226s === RUN TestWindowsHostAddress/With_NAT 226s === PAUSE TestWindowsHostAddress/With_NAT 226s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 226s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 226s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 226s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 226s === CONT TestProDetach/success_on_unattached_distro 226s --- PASS: TestProAttach (0.00s) 226s --- PASS: TestProAttach/success (0.80s) 226s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.04s) 226s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 226s === CONT TestProDetach/success_on_attached_distro 226s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 226s === CONT TestProStatus/success_on_unattached_distro 226s --- PASS: TestProDetach (0.00s) 226s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (0.98s) 226s --- PASS: TestProDetach/success_on_unattached_distro (0.88s) 226s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (0.89s) 226s --- PASS: TestProDetach/success_on_attached_distro (1.11s) 226s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.00s) 226s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 226s === CONT TestProStatus/error_on_'pro_attach'_error 226s === CONT TestProStatus/success_on_attached_distro 226s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 226s --- PASS: TestProStatus (0.00s) 226s --- PASS: TestProStatus/success_on_unattached_distro (0.89s) 226s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (0.95s) 226s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.04s) 226s --- PASS: TestProStatus/success_on_attached_distro (1.00s) 226s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 226s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 226s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 226s system_test.go:245: Removing default proc/mounts 226s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 226s system_test.go:245: Removing default proc/mounts 226s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 226s === CONT TestUserProfileDir/Error_on_cmd.exe_error 226s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 226s === CONT TestUserProfileDir/Error_on_wslpath_error 226s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 226s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 226s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 226s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 226s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 227s --- PASS: TestUserProfileDir (0.00s) 227s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (1.79s) 227s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (1.88s) 227s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 227s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 227s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (0.94s) 227s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.11s) 227s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (1.77s) 227s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (1.78s) 227s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 227s --- PASS: TestUserProfileDir/Error_on_wslpath_error (1.99s) 227s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (1.39s) 227s === CONT TestWslDistroName/Success_using_wslpath 227s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 227s --- PASS: TestWslDistroName (0.00s) 227s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 227s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (0.58s) 227s --- PASS: TestWslDistroName/Success_using_wslpath (0.82s) 227s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (0.83s) 227s === CONT TestInfo/Error_when_WslDistroName_fails 227s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 227s === CONT TestInfo/Error_when_pro_status_command_fails 227s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 227s === CONT TestInfo/Success 227s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 227s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 227s --- PASS: TestInfo (0.00s) 227s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (0.86s) 227s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.09s) 227s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (0.95s) 227s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.00s) 227s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (0.94s) 227s --- PASS: TestInfo/Success (1.03s) 227s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (0.96s) 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 227s === CONT TestLandscapeEnable/Regular_with_additional_keys 227s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 227s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 227s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 227s === NAME TestLandscapeEnable/Regular_with_additional_keys 227s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 227s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 227s === NAME TestLandscapeEnable/Refresh_computer_title_if_changed 227s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 227s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 227s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 227s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 227s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 227s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 227s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 227s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 227s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 227s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 227s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 227s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 227s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 227s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 227s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 227s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 227s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 227s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 227s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 227s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 227s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 227s === CONT TestLandscapeDisable/Success 227s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 227s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 227s --- PASS: TestLandscapeEnable (0.00s) 227s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (1.96s) 227s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (1.87s) 227s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (0.78s) 227s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.67s) 227s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (0.83s) 227s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (1.88s) 227s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (1.86s) 227s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (2.25s) 227s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (1.15s) 227s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 227s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (0.80s) 227s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (1.34s) 227s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (0.59s) 227s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.00s) 227s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (0.90s) 227s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (1.79s) 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 227s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 227s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 227s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 227s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 227s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 227s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 227s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 227s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 227s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 227s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 227s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 227s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 227s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 227s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 227s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_due_whitespace_changes 227s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 227s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 227s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 227s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 227s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 227s === NAME TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 227s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 227s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 227s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (0.01s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.01s) 227s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (0.01s) 227s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (1.73s) 227s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (2.07s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (1.15s) 227s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (0.01s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (1.79s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (0.01s) 227s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (1.44s) 227s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (0.69s) 227s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (0.66s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (0.55s) 227s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes (0.62s) 227s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (1.22s) 227s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (1.19s) 227s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (0.66s) 227s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (0.63s) 227s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 227s === CONT TestWindowsHostAddress/With_NAT 227s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 227s --- PASS: TestLandscapeDisable (0.00s) 227s --- PASS: TestLandscapeDisable/Success (1.00s) 227s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (0.61s) 227s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 227s === CONT TestWindowsHostAddress/Without_NAT 227s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 227s --- PASS: TestWindowsHostAddress (0.00s) 227s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (0.57s) 227s --- PASS: TestWindowsHostAddress/With_NAT (0.59s) 227s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (0.64s) 227s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (0.66s) 227s --- PASS: TestWindowsHostAddress/Without_NAT (0.61s) 227s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (0.59s) 227s PASS 227s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 35.362s 227s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 227s autopkgtest [19:07:56]: test command1: -----------------------] 227s autopkgtest [19:07:56]: test command1: - - - - - - - - - - results - - - - - - - - - - 227s command1 PASS 228s autopkgtest [19:07:57]: @@@@@@@@@@@@@@@@@@@@ summary 228s command1 PASS