0s autopkgtest [00:34:26]: starting date and time: 2024-05-24 00:34:26+0000 0s autopkgtest [00:34:26]: git checkout: d60bd36 autopkgtest-build-lxd: Add missing remote prefix in get_boot_id(). 0s autopkgtest [00:34:26]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.wjr5kqfj/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:ubuntu-advantage-tools --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/32.2~24.04' -- lxd -r lxd-armhf-10.145.243.236 lxd-armhf-10.145.243.236:autopkgtest/ubuntu/noble/armhf 24s autopkgtest [00:34:50]: testbed dpkg architecture: armhf 27s autopkgtest [00:34:53]: testbed apt version: 2.7.14build2 27s autopkgtest [00:34:53]: @@@@@@@@@@@@@@@@@@@@ test bed setup 34s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [256 kB] 34s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [26.8 kB] 34s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [10.9 kB] 34s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [3828 B] 34s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2080 B] 34s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [72.8 kB] 34s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 34s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 34s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [55.2 kB] 34s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 34s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 36s Fetched 438 kB in 1s (632 kB/s) 36s Reading package lists... 53s tee: /proc/self/fd/2: Permission denied 69s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 70s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 70s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 70s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 71s Reading package lists... 71s Reading package lists... 71s Building dependency tree... 71s Reading state information... 71s Calculating upgrade... 72s The following packages will be upgraded: 72s dhcpcd-base initramfs-tools initramfs-tools-bin initramfs-tools-core 72s klibc-utils libklibc libssl3t64 openssl python3-idna ubuntu-pro-client 72s ubuntu-pro-client-l10n 72s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 72s Need to get 3238 kB of archives. 72s After this operation, 137 kB of additional disk space will be used. 72s Get:1 http://ftpmaster.internal/ubuntu noble-updates/main armhf libssl3t64 armhf 3.0.13-0ubuntu3.1 [1560 kB] 72s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main armhf dhcpcd-base armhf 1:10.0.6-1ubuntu3.1 [186 kB] 72s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main armhf openssl armhf 3.0.13-0ubuntu3.1 [975 kB] 72s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 32.2~24.04 [19.4 kB] 72s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 32.2~24.04 [230 kB] 72s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf initramfs-tools all 0.142ubuntu25.1 [9078 B] 72s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf initramfs-tools-core all 0.142ubuntu25.1 [49.9 kB] 72s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main armhf initramfs-tools-bin armhf 0.142ubuntu25.1 [20.5 kB] 72s Get:9 http://ftpmaster.internal/ubuntu noble-updates/main armhf klibc-utils armhf 2.0.13-4ubuntu0.1 [93.8 kB] 72s Get:10 http://ftpmaster.internal/ubuntu noble-updates/main armhf libklibc armhf 2.0.13-4ubuntu0.1 [45.0 kB] 72s Get:11 http://ftpmaster.internal/ubuntu noble-updates/main armhf python3-idna all 3.6-2ubuntu0.1 [49.0 kB] 73s Fetched 3238 kB in 1s (4342 kB/s) 73s (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 ... 57874 files and directories currently installed.) 73s Preparing to unpack .../libssl3t64_3.0.13-0ubuntu3.1_armhf.deb ... 73s Unpacking libssl3t64:armhf (3.0.13-0ubuntu3.1) over (3.0.13-0ubuntu3) ... 73s Setting up libssl3t64:armhf (3.0.13-0ubuntu3.1) ... 73s (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 ... 57874 files and directories currently installed.) 73s Preparing to unpack .../0-dhcpcd-base_1%3a10.0.6-1ubuntu3.1_armhf.deb ... 73s Unpacking dhcpcd-base (1:10.0.6-1ubuntu3.1) over (1:10.0.6-1ubuntu3) ... 73s Preparing to unpack .../1-openssl_3.0.13-0ubuntu3.1_armhf.deb ... 73s Unpacking openssl (3.0.13-0ubuntu3.1) over (3.0.13-0ubuntu3) ... 73s Preparing to unpack .../2-ubuntu-pro-client-l10n_32.2~24.04_armhf.deb ... 73s Unpacking ubuntu-pro-client-l10n (32.2~24.04) over (31.2.3) ... 73s Preparing to unpack .../3-ubuntu-pro-client_32.2~24.04_armhf.deb ... 73s Unpacking ubuntu-pro-client (32.2~24.04) over (31.2.3) ... 73s Preparing to unpack .../4-initramfs-tools_0.142ubuntu25.1_all.deb ... 73s Unpacking initramfs-tools (0.142ubuntu25.1) over (0.142ubuntu25) ... 73s Preparing to unpack .../5-initramfs-tools-core_0.142ubuntu25.1_all.deb ... 73s Unpacking initramfs-tools-core (0.142ubuntu25.1) over (0.142ubuntu25) ... 73s Preparing to unpack .../6-initramfs-tools-bin_0.142ubuntu25.1_armhf.deb ... 73s Unpacking initramfs-tools-bin (0.142ubuntu25.1) over (0.142ubuntu25) ... 73s Preparing to unpack .../7-klibc-utils_2.0.13-4ubuntu0.1_armhf.deb ... 73s Unpacking klibc-utils (2.0.13-4ubuntu0.1) over (2.0.13-4) ... 73s Preparing to unpack .../8-libklibc_2.0.13-4ubuntu0.1_armhf.deb ... 73s Unpacking libklibc:armhf (2.0.13-4ubuntu0.1) over (2.0.13-4) ... 73s Preparing to unpack .../9-python3-idna_3.6-2ubuntu0.1_all.deb ... 74s Unpacking python3-idna (3.6-2ubuntu0.1) over (3.6-2) ... 74s Setting up libklibc:armhf (2.0.13-4ubuntu0.1) ... 74s Setting up python3-idna (3.6-2ubuntu0.1) ... 74s Setting up dhcpcd-base (1:10.0.6-1ubuntu3.1) ... 74s Setting up klibc-utils (2.0.13-4ubuntu0.1) ... 74s Setting up openssl (3.0.13-0ubuntu3.1) ... 74s Setting up ubuntu-pro-client (32.2~24.04) ... 74s Installing new version of config file /etc/apparmor.d/ubuntu_pro_apt_news ... 74s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 74s 74s apparmor_parser: Unable to replace "apt_methods". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 74s 74s apparmor_parser: Unable to replace "ubuntu_pro_esm_cache". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 74s 75s Setting up initramfs-tools-bin (0.142ubuntu25.1) ... 75s Setting up ubuntu-pro-client-l10n (32.2~24.04) ... 75s Setting up initramfs-tools-core (0.142ubuntu25.1) ... 75s Setting up initramfs-tools (0.142ubuntu25.1) ... 75s update-initramfs: deferring update (trigger activated) 75s Processing triggers for man-db (2.12.0-4build2) ... 76s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 77s Processing triggers for initramfs-tools (0.142ubuntu25.1) ... 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 77s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s autopkgtest [00:35:45]: rebooting testbed after setup commands that affected boot 118s autopkgtest [00:36:24]: testbed running kernel: Linux 5.15.0-107-generic #117-Ubuntu SMP Mon Apr 29 14:37:09 UTC 2024 143s autopkgtest [00:36:49]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 153s Get:1 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (dsc) [1420 B] 153s Get:2 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (tar) [1762 kB] 153s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 153s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 153s gpgv: Can't check signature: No public key 153s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 154s autopkgtest [00:37:00]: testing package wsl-pro-service version 0.1.4 156s autopkgtest [00:37:02]: build not needed 158s autopkgtest [00:37:04]: test command1: preparing testbed 168s Reading package lists... 169s Building dependency tree... 169s Reading state information... 169s Starting pkgProblemResolver with broken count: 0 169s Starting 2 pkgProblemResolver with broken count: 0 169s Done 170s The following additional packages will be installed: 170s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 170s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 170s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 170s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 170s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 170s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 170s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 170s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 170s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 170s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 170s rpcsvc-proto 170s Suggested packages: 170s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 170s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 170s gcc-doc gdb-arm-linux-gnueabihf gettext-doc libasprintf-dev libgettextpo-dev 170s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 170s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 170s Recommended packages: 170s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 170s libltdl-dev libmail-sendmail-perl 170s The following NEW packages will be installed: 170s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 170s cpp cpp-13 cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper 170s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 170s g++-13 g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 170s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 170s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 170s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 170s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 170s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 170s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 170s rpcsvc-proto 170s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 170s Need to get 95.3 MB/95.3 MB of archives. 170s After this operation, 377 MB of additional disk space will be used. 170s Get:1 /tmp/autopkgtest.7wWDYI/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [760 B] 170s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf m4 armhf 1.4.19-4build1 [235 kB] 171s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf autoconf all 2.71-3 [339 kB] 171s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf autotools-dev all 20220109.1 [44.9 kB] 171s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf automake all 1:1.16.5-1.3ubuntu1 [558 kB] 171s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf autopoint all 0.21-14ubuntu2 [422 kB] 171s Get:7 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc-dev-bin armhf 2.39-0ubuntu8.1 [19.1 kB] 171s Get:8 http://ftpmaster.internal/ubuntu noble/main armhf linux-libc-dev armhf 6.8.0-31.31 [1557 kB] 171s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf libcrypt-dev armhf 1:4.4.36-4build1 [120 kB] 171s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf rpcsvc-proto armhf 1.4.2-0ubuntu7 [62.2 kB] 171s Get:11 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc6-dev armhf 2.39-0ubuntu8.1 [1351 kB] 171s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-base armhf 13.2.0-23ubuntu4 [48.9 kB] 171s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libisl23 armhf 0.26-3build1 [544 kB] 171s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf libmpc3 armhf 1.3.1-1build1 [46.7 kB] 171s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [8759 kB] 171s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-23ubuntu4 [1034 B] 171s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 171s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 171s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240412-0ubuntu1 [39.0 kB] 171s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240412-0ubuntu1 [125 kB] 171s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240412-0ubuntu1 [7816 B] 171s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240412-0ubuntu1 [2942 kB] 171s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240412-0ubuntu1 [1154 kB] 171s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-23ubuntu4 [899 kB] 171s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [16.9 MB] 172s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13 armhf 13.2.0-23ubuntu4 [453 kB] 172s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf gcc-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [1220 B] 172s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf gcc armhf 4:13.2.0-7ubuntu1 [5022 B] 172s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf libstdc++-13-dev armhf 13.2.0-23ubuntu4 [2456 kB] 172s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf g++-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [9941 kB] 172s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf g++-13 armhf 13.2.0-23ubuntu4 [14.5 kB] 172s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf g++-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [966 B] 172s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf g++ armhf 4:13.2.0-7ubuntu1 [1090 B] 172s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf build-essential armhf 12.10ubuntu1 [4928 B] 172s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 172s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf libtool all 2.4.7-7build1 [166 kB] 172s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf dh-autoreconf all 20 [16.1 kB] 172s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 172s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libsub-override-perl all 0.10-1 [10.0 kB] 172s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 172s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf dh-strip-nondeterminism all 1.13.1-1 [5362 B] 173s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf libdw1t64 armhf 0.190-1.1build4 [235 kB] 173s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5build2 [42.3 kB] 173s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1build6 [116 kB] 173s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu2 [800 kB] 173s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 173s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 173s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.14.1ubuntu5 [869 kB] 173s Get:49 http://ftpmaster.internal/ubuntu noble-updates/universe armhf dh-apport all 2.28.1-0ubuntu3 [17.2 kB] 173s Get:50 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.22-src all 1.22.2-2 [19.7 MB] 174s Get:51 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.22-go armhf 1.22.2-2 [23.7 MB] 175s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.22~2build1 [5078 B] 175s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.22~2build1 [43.9 kB] 175s Get:54 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 175s Fetched 95.3 MB in 5s (20.7 MB/s) 175s Selecting previously unselected package m4. 175s (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 ... 57901 files and directories currently installed.) 175s Preparing to unpack .../00-m4_1.4.19-4build1_armhf.deb ... 175s Unpacking m4 (1.4.19-4build1) ... 175s Selecting previously unselected package autoconf. 175s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 175s Unpacking autoconf (2.71-3) ... 175s Selecting previously unselected package autotools-dev. 175s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 175s Unpacking autotools-dev (20220109.1) ... 175s Selecting previously unselected package automake. 175s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 175s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 175s Selecting previously unselected package autopoint. 175s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 175s Unpacking autopoint (0.21-14ubuntu2) ... 175s Selecting previously unselected package libc-dev-bin. 175s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu8.1_armhf.deb ... 175s Unpacking libc-dev-bin (2.39-0ubuntu8.1) ... 175s Selecting previously unselected package linux-libc-dev:armhf. 175s Preparing to unpack .../06-linux-libc-dev_6.8.0-31.31_armhf.deb ... 175s Unpacking linux-libc-dev:armhf (6.8.0-31.31) ... 176s Selecting previously unselected package libcrypt-dev:armhf. 176s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4build1_armhf.deb ... 176s Unpacking libcrypt-dev:armhf (1:4.4.36-4build1) ... 176s Selecting previously unselected package rpcsvc-proto. 176s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu7_armhf.deb ... 176s Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ... 176s Selecting previously unselected package libc6-dev:armhf. 176s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu8.1_armhf.deb ... 176s Unpacking libc6-dev:armhf (2.39-0ubuntu8.1) ... 176s Selecting previously unselected package gcc-13-base:armhf. 176s Preparing to unpack .../10-gcc-13-base_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking gcc-13-base:armhf (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package libisl23:armhf. 176s Preparing to unpack .../11-libisl23_0.26-3build1_armhf.deb ... 176s Unpacking libisl23:armhf (0.26-3build1) ... 176s Selecting previously unselected package libmpc3:armhf. 176s Preparing to unpack .../12-libmpc3_1.3.1-1build1_armhf.deb ... 176s Unpacking libmpc3:armhf (1.3.1-1build1) ... 176s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 176s Preparing to unpack .../13-cpp-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package cpp-13. 176s Preparing to unpack .../14-cpp-13_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package cpp-arm-linux-gnueabihf. 176s Preparing to unpack .../15-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 176s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package cpp. 176s Preparing to unpack .../16-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 176s Unpacking cpp (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package libcc1-0:armhf. 176s Preparing to unpack .../17-libcc1-0_14-20240412-0ubuntu1_armhf.deb ... 176s Unpacking libcc1-0:armhf (14-20240412-0ubuntu1) ... 177s Selecting previously unselected package libgomp1:armhf. 177s Preparing to unpack .../18-libgomp1_14-20240412-0ubuntu1_armhf.deb ... 177s Unpacking libgomp1:armhf (14-20240412-0ubuntu1) ... 177s Selecting previously unselected package libatomic1:armhf. 177s Preparing to unpack .../19-libatomic1_14-20240412-0ubuntu1_armhf.deb ... 177s Unpacking libatomic1:armhf (14-20240412-0ubuntu1) ... 177s Selecting previously unselected package libasan8:armhf. 177s Preparing to unpack .../20-libasan8_14-20240412-0ubuntu1_armhf.deb ... 177s Unpacking libasan8:armhf (14-20240412-0ubuntu1) ... 177s Selecting previously unselected package libubsan1:armhf. 177s Preparing to unpack .../21-libubsan1_14-20240412-0ubuntu1_armhf.deb ... 177s Unpacking libubsan1:armhf (14-20240412-0ubuntu1) ... 177s Selecting previously unselected package libgcc-13-dev:armhf. 177s Preparing to unpack .../22-libgcc-13-dev_13.2.0-23ubuntu4_armhf.deb ... 177s Unpacking libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 177s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 177s Preparing to unpack .../23-gcc-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 177s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 177s Selecting previously unselected package gcc-13. 177s Preparing to unpack .../24-gcc-13_13.2.0-23ubuntu4_armhf.deb ... 177s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 177s Selecting previously unselected package gcc-arm-linux-gnueabihf. 177s Preparing to unpack .../25-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 177s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package gcc. 177s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 177s Unpacking gcc (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package libstdc++-13-dev:armhf. 177s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-23ubuntu4_armhf.deb ... 177s Unpacking libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 178s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 178s Preparing to unpack .../28-g++-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 178s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 178s Selecting previously unselected package g++-13. 178s Preparing to unpack .../29-g++-13_13.2.0-23ubuntu4_armhf.deb ... 178s Unpacking g++-13 (13.2.0-23ubuntu4) ... 178s Selecting previously unselected package g++-arm-linux-gnueabihf. 178s Preparing to unpack .../30-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 178s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 178s Selecting previously unselected package g++. 178s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 178s Unpacking g++ (4:13.2.0-7ubuntu1) ... 178s Selecting previously unselected package build-essential. 178s Preparing to unpack .../32-build-essential_12.10ubuntu1_armhf.deb ... 178s Unpacking build-essential (12.10ubuntu1) ... 178s Selecting previously unselected package libdebhelper-perl. 178s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 178s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 178s Selecting previously unselected package libtool. 178s Preparing to unpack .../34-libtool_2.4.7-7build1_all.deb ... 178s Unpacking libtool (2.4.7-7build1) ... 178s Selecting previously unselected package dh-autoreconf. 178s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 178s Unpacking dh-autoreconf (20) ... 178s Selecting previously unselected package libarchive-zip-perl. 178s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 178s Unpacking libarchive-zip-perl (1.68-1) ... 178s Selecting previously unselected package libsub-override-perl. 178s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 178s Unpacking libsub-override-perl (0.10-1) ... 178s Selecting previously unselected package libfile-stripnondeterminism-perl. 178s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 178s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 178s Selecting previously unselected package dh-strip-nondeterminism. 178s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 178s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 178s Selecting previously unselected package libdw1t64:armhf. 178s Preparing to unpack .../40-libdw1t64_0.190-1.1build4_armhf.deb ... 178s Unpacking libdw1t64:armhf (0.190-1.1build4) ... 179s Selecting previously unselected package debugedit. 179s Preparing to unpack .../41-debugedit_1%3a5.0-5build2_armhf.deb ... 179s Unpacking debugedit (1:5.0-5build2) ... 179s Selecting previously unselected package dwz. 179s Preparing to unpack .../42-dwz_0.15-1build6_armhf.deb ... 179s Unpacking dwz (0.15-1build6) ... 179s Selecting previously unselected package gettext. 179s Preparing to unpack .../43-gettext_0.21-14ubuntu2_armhf.deb ... 179s Unpacking gettext (0.21-14ubuntu2) ... 179s Selecting previously unselected package intltool-debian. 179s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 179s Unpacking intltool-debian (0.35.0+20060710.6) ... 179s Selecting previously unselected package po-debconf. 179s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 179s Unpacking po-debconf (1.0.21+nmu1) ... 179s Selecting previously unselected package debhelper. 179s Preparing to unpack .../46-debhelper_13.14.1ubuntu5_all.deb ... 179s Unpacking debhelper (13.14.1ubuntu5) ... 179s Selecting previously unselected package dh-apport. 179s Preparing to unpack .../47-dh-apport_2.28.1-0ubuntu3_all.deb ... 179s Unpacking dh-apport (2.28.1-0ubuntu3) ... 179s Selecting previously unselected package golang-1.22-src. 179s Preparing to unpack .../48-golang-1.22-src_1.22.2-2_all.deb ... 179s Unpacking golang-1.22-src (1.22.2-2) ... 182s Selecting previously unselected package golang-1.22-go. 182s Preparing to unpack .../49-golang-1.22-go_1.22.2-2_armhf.deb ... 182s Unpacking golang-1.22-go (1.22.2-2) ... 182s Selecting previously unselected package golang-src. 183s Preparing to unpack .../50-golang-src_2%3a1.22~2build1_all.deb ... 183s Unpacking golang-src (2:1.22~2build1) ... 183s Selecting previously unselected package golang-go:armhf. 183s Preparing to unpack .../51-golang-go_2%3a1.22~2build1_armhf.deb ... 183s Unpacking golang-go:armhf (2:1.22~2build1) ... 183s Selecting previously unselected package dh-golang. 183s Preparing to unpack .../52-dh-golang_1.62_all.deb ... 183s Unpacking dh-golang (1.62) ... 183s Selecting previously unselected package autopkgtest-satdep. 183s Preparing to unpack .../53-1-autopkgtest-satdep.deb ... 183s Unpacking autopkgtest-satdep (0) ... 183s Setting up libarchive-zip-perl (1.68-1) ... 183s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 183s Setting up linux-libc-dev:armhf (6.8.0-31.31) ... 183s Setting up m4 (1.4.19-4build1) ... 183s Setting up libgomp1:armhf (14-20240412-0ubuntu1) ... 183s Setting up dh-apport (2.28.1-0ubuntu3) ... 183s Setting up libdw1t64:armhf (0.190-1.1build4) ... 183s Setting up autotools-dev (20220109.1) ... 183s Setting up rpcsvc-proto (1.4.2-0ubuntu7) ... 183s Setting up gcc-13-base:armhf (13.2.0-23ubuntu4) ... 183s Setting up golang-1.22-src (1.22.2-2) ... 183s Setting up libmpc3:armhf (1.3.1-1build1) ... 183s Setting up libatomic1:armhf (14-20240412-0ubuntu1) ... 183s Setting up autopoint (0.21-14ubuntu2) ... 183s Setting up autoconf (2.71-3) ... 183s Setting up libubsan1:armhf (14-20240412-0ubuntu1) ... 183s Setting up dwz (0.15-1build6) ... 183s Setting up libcrypt-dev:armhf (1:4.4.36-4build1) ... 183s Setting up libasan8:armhf (14-20240412-0ubuntu1) ... 183s Setting up debugedit (1:5.0-5build2) ... 183s Setting up libsub-override-perl (0.10-1) ... 183s Setting up libisl23:armhf (0.26-3build1) ... 183s Setting up libc-dev-bin (2.39-0ubuntu8.1) ... 183s Setting up golang-src (2:1.22~2build1) ... 183s Setting up libcc1-0:armhf (14-20240412-0ubuntu1) ... 183s Setting up automake (1:1.16.5-1.3ubuntu1) ... 183s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 183s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 183s Setting up gettext (0.21-14ubuntu2) ... 183s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 183s Setting up golang-1.22-go (1.22.2-2) ... 183s Setting up intltool-debian (0.35.0+20060710.6) ... 183s Setting up dh-strip-nondeterminism (1.13.1-1) ... 183s Setting up libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 183s Setting up libc6-dev:armhf (2.39-0ubuntu8.1) ... 183s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 183s Setting up libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 183s Setting up cpp-13 (13.2.0-23ubuntu4) ... 183s Setting up golang-go:armhf (2:1.22~2build1) ... 183s Setting up po-debconf (1.0.21+nmu1) ... 183s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 183s Setting up gcc-13 (13.2.0-23ubuntu4) ... 183s Setting up cpp (4:13.2.0-7ubuntu1) ... 183s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 183s Setting up g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 183s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 183s Setting up g++-13 (13.2.0-23ubuntu4) ... 183s Setting up libtool (2.4.7-7build1) ... 183s Setting up gcc (4:13.2.0-7ubuntu1) ... 183s Setting up dh-autoreconf (20) ... 183s Setting up g++ (4:13.2.0-7ubuntu1) ... 183s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 183s Setting up build-essential (12.10ubuntu1) ... 183s Setting up debhelper (13.14.1ubuntu5) ... 183s Setting up dh-golang (1.62) ... 183s Setting up autopkgtest-satdep (0) ... 183s Processing triggers for man-db (2.12.0-4build2) ... 184s Processing triggers for install-info (7.1-3build2) ... 184s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 195s (Reading database ... 76040 files and directories currently installed.) 195s Removing autopkgtest-satdep (0) ... 201s autopkgtest [00:37:47]: test command1: ./debian/tests/test 201s autopkgtest [00:37:47]: test command1: [----------------------- 225s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 225s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 225s === RUN TestRunSignal 225s === RUN TestRunSignal/Send_SIGTERM_exits 225s INFO Starting WSL Pro Service version Dev 225s === RUN TestRunSignal/Send_SIGINT_exits 225s INFO Starting WSL Pro Service version Dev 225s --- PASS: TestRunSignal (0.24s) 225s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.11s) 225s --- PASS: TestRunSignal/Send_SIGINT_exits (0.13s) 225s === RUN TestRun 225s === PAUSE TestRun 225s === CONT TestRun 225s === RUN TestRun/Run_and_exit_successfully 225s === PAUSE TestRun/Run_and_exit_successfully 225s === RUN TestRun/Run_and_return_error 225s === PAUSE TestRun/Run_and_return_error 225s === RUN TestRun/Run_and_return_usage_error 225s === PAUSE TestRun/Run_and_return_usage_error 225s === RUN TestRun/Run_and_usage_error_only_does_not_fail 225s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 225s === CONT TestRun/Run_and_exit_successfully 225s === CONT TestRun/Run_and_usage_error_only_does_not_fail 225s === CONT TestRun/Run_and_return_error 225s INFO Starting WSL Pro Service version Dev 225s INFO Starting WSL Pro Service version Dev 225s INFO Starting WSL Pro Service version Dev 225s === CONT TestRun/Run_and_return_usage_error 225s INFO Starting WSL Pro Service version Dev 225s ERROR context.Background Error requested 225s ERROR context.Background Error requested 225s --- PASS: TestRun (0.00s) 225s --- PASS: TestRun/Run_and_exit_successfully (0.12s) 225s --- PASS: TestRun/Run_and_return_error (0.12s) 225s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.14s) 225s --- PASS: TestRun/Run_and_return_usage_error (0.15s) 225s PASS 225s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.694s 226s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 276s === RUN TestHelp 276s --- PASS: TestHelp (0.28s) 276s === RUN TestCompletion 276s --- PASS: TestCompletion (0.10s) 276s === RUN TestVersion 276s --- PASS: TestVersion (0.06s) 276s === RUN TestNoUsageError 276s --- PASS: TestNoUsageError (0.00s) 276s === RUN TestUsageError 276s === PAUSE TestUsageError 276s === RUN TestCanQuitWhenExecute 276s === PAUSE TestCanQuitWhenExecute 276s === RUN TestCanQuitTwice 276s === PAUSE TestCanQuitTwice 276s === RUN TestAppCanQuitWithoutExecute 276s === PAUSE TestAppCanQuitWithoutExecute 276s === RUN TestAppRunFailsOnComponentsCreationAndQuit 276s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 276s === RUN TestAppGetRootCmd 276s === PAUSE TestAppGetRootCmd 276s === RUN TestWithProMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithProMock (0.00s) 276s === RUN TestWithWslPathMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithWslPathMock (0.00s) 276s === RUN TestWithWslInfoMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithWslInfoMock (0.00s) 276s === RUN TestWithCmdExeMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithCmdExeMock (0.00s) 276s === CONT TestUsageError 276s --- PASS: TestUsageError (0.00s) 276s === CONT TestAppGetRootCmd 276s --- PASS: TestAppGetRootCmd (0.00s) 276s === CONT TestAppRunFailsOnComponentsCreationAndQuit 276s === CONT TestCanQuitTwice 276s === CONT TestAppCanQuitWithoutExecute 276s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 276s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 276s === CONT TestCanQuitWhenExecute 276s time="2024-05-24T00:38:12Z" level=debug msg="/tmp/autopkgtest.7wWDYI/build.opE/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 276s time="2024-05-24T00:38:12Z" level=debug msg="/tmp/autopkgtest.7wWDYI/build.opE/src/internal/daemon/daemon.go:75 New() Building new daemon" 276s time="2024-05-24T00:38:12Z" level=info msg="/tmp/autopkgtest.7wWDYI/build.opE/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43697" 276s time="2024-05-24T00:38:12Z" level=debug msg="/tmp/autopkgtest.7wWDYI/build.opE/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 276s time="2024-05-24T00:38:12Z" level=debug msg="/tmp/autopkgtest.7wWDYI/build.opE/src/internal/daemon/daemon.go:75 New() Building new daemon" 276s time="2024-05-24T00:38:12Z" level=info msg="/tmp/autopkgtest.7wWDYI/build.opE/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42933" 276s time="2024-05-24T00:38:54Z" 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:43697: connect: connection refused\"" 276s --- PASS: TestCanQuitWhenExecute (42.27s) 276s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (42.61s) 276s time="2024-05-24T00:39:02Z" level=warning msg="Daemon: disconnected from Windows host" 276s time="2024-05-24T00:39:02Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 276s time="2024-05-24T00:39:02Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 276s time="2024-05-24T00:39:02Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 276s --- PASS: TestCanQuitTwice (49.98s) 276s PASS 276s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 51.219s 276s === RUN TestApplyProToken 276s === PAUSE TestApplyProToken 276s === RUN TestApplyLandscapeConfig 276s === PAUSE TestApplyLandscapeConfig 276s === RUN TestWithProMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithProMock (0.01s) 276s === RUN TestWithLandscapeConfigMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithLandscapeConfigMock (0.00s) 276s === RUN TestWithWslPathMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithWslPathMock (0.00s) 276s === RUN TestWithWslInfoMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithWslInfoMock (0.00s) 276s === RUN TestWithCmdExeMock 276s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 276s --- SKIP: TestWithCmdExeMock (0.00s) 276s === CONT TestApplyProToken 276s === RUN TestApplyProToken/Success_attaching 276s === PAUSE TestApplyProToken/Success_attaching 276s === RUN TestApplyProToken/Success_detaching 276s === PAUSE TestApplyProToken/Success_detaching 276s === RUN TestApplyProToken/Error_calling_pro_detach 276s === PAUSE TestApplyProToken/Error_calling_pro_detach 276s === RUN TestApplyProToken/Error_calling_pro_attach 276s === PAUSE TestApplyProToken/Error_calling_pro_attach 276s === CONT TestApplyProToken/Success_attaching 276s === CONT TestApplyProToken/Error_calling_pro_attach 276s === CONT TestApplyLandscapeConfig 276s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 276s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 276s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 276s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 276s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 276s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 276s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 276s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 276s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 276s === CONT TestApplyProToken/Error_calling_pro_detach 276s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 276s === CONT TestApplyProToken/Success_detaching 276s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 276s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyProToken: Received empty token: detaching" 276s time="2024-05-24T00:38:12Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 276s --- PASS: TestApplyLandscapeConfig (0.00s) 276s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (13.09s) 276s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (13.25s) 276s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (17.72s) 276s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (22.60s) 276s --- PASS: TestApplyProToken (0.00s) 276s --- PASS: TestApplyProToken/Error_calling_pro_detach (15.43s) 276s --- PASS: TestApplyProToken/Success_detaching (15.46s) 276s --- PASS: TestApplyProToken/Error_calling_pro_attach (25.49s) 276s --- PASS: TestApplyProToken/Success_attaching (30.46s) 276s PASS 276s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 31.871s 341s === RUN TestNew 341s === PAUSE TestNew 341s === RUN TestServe 341s === PAUSE TestServe 341s === RUN TestServeAndQuit 341s === PAUSE TestServeAndQuit 341s === RUN TestReconnection 341s === PAUSE TestReconnection 341s === RUN TestWithProMock 341s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 341s --- SKIP: TestWithProMock (0.00s) 341s === RUN TestWithWslPathMock 341s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 341s --- SKIP: TestWithWslPathMock (0.00s) 341s === RUN TestWithWslInfoMock 341s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 341s --- SKIP: TestWithWslInfoMock (0.00s) 341s === RUN TestWithCmdExeMock 341s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 341s --- SKIP: TestWithCmdExeMock (0.00s) 341s === CONT TestNew 341s === RUN TestNew/Success 341s === PAUSE TestNew/Success 341s === RUN TestNew/Error_when_WslPath_returns_error 341s === PAUSE TestNew/Error_when_WslPath_returns_error 341s === CONT TestNew/Success 341s === CONT TestServeAndQuit 341s === RUN TestServeAndQuit/Success_with_graceful_quit 341s === PAUSE TestServeAndQuit/Success_with_graceful_quit 341s === RUN TestServeAndQuit/Success_with_forceful_quit 341s === PAUSE TestServeAndQuit/Success_with_forceful_quit 341s === RUN TestServeAndQuit/Success_with_double_quit 341s === PAUSE TestServeAndQuit/Success_with_double_quit 341s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 341s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 341s === CONT TestServeAndQuit/Success_with_graceful_quit 341s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 341s === CONT TestServeAndQuit/Success_with_double_quit 341s === CONT TestServeAndQuit/Success_with_forceful_quit 341s === CONT TestServe 341s === RUN TestServe/Success_with_systemd_notifier_returning_true 341s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 341s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 341s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 341s === RUN TestServe/No_connection_because_there_are_no_certificates 341s === PAUSE TestServe/No_connection_because_there_are_no_certificates 341s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 341s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 341s === RUN TestServe/No_connection_because_there_is_no_server 341s === PAUSE TestServe/No_connection_because_there_is_no_server 341s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 341s === CONT TestReconnection 341s === CONT TestNew/Error_when_WslPath_returns_error 341s === RUN TestReconnection/Success_connecting_after_failing_to_connect 341s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 341s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 341s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 341s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 341s === RUN TestServe/Error_because_the_context_is_pre-cancelled 341s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 341s === RUN TestServe/Success 341s === PAUSE TestServe/Success 341s === RUN TestServe/No_connection_because_the_port_file_is_empty 341s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 341s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 341s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 341s === RUN TestServe/No_connection_because_the_port_file_has_port_0 341s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 341s === RUN TestServe/Error_because_the_notifier_returns_an_error 341s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 341s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 341s === CONT TestReconnection/Success_connecting_after_failing_to_connect 341s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 341s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 341s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 341s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36179" 341s time="2024-05-24T00:38:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45163" 341s time="2024-05-24T00:38:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40591" 341s time="2024-05-24T00:38:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44873" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:12Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:39Z" level=info msg="Stopping daemon requested." 341s time="2024-05-24T00:38:39Z" level=info msg="Waiting for active requests to close." 341s time="2024-05-24T00:38:39Z" level=info msg="Stopping daemon requested." 341s time="2024-05-24T00:38:39Z" level=info msg="Waiting for active requests to close." 341s time="2024-05-24T00:38:39Z" level=debug msg="Updated systemd status to \"Stopped\"" 341s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 341s time="2024-05-24T00:38:40Z" level=debug msg="Building new daemon" 341s === CONT TestServe/Success_with_systemd_notifier_returning_true 341s time="2024-05-24T00:38:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42831" 341s time="2024-05-24T00:38:41Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:42Z" level=debug msg="Ready state sent to systemd" 341s time="2024-05-24T00:38:42Z" level=info msg="Daemon: connecting to Windows Agent" 341s time="2024-05-24T00:38:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 341s time="2024-05-24T00:38:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32799" 341s time="2024-05-24T00:38:42Z" level=debug msg="Ready state sent to systemd" 341s time="2024-05-24T00:38:42Z" level=info msg="Daemon: connecting to Windows Agent" 341s time="2024-05-24T00:38:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 341s time="2024-05-24T00:38:42Z" level=debug msg="Ready state sent to systemd" 341s time="2024-05-24T00:38:42Z" level=info msg="Daemon: connecting to Windows Agent" 341s time="2024-05-24T00:38:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 341s time="2024-05-24T00:38:42Z" 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_connect909460097/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect909460097/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 341s time="2024-05-24T00:38:42Z" level=info msg="Reconnecting to Windows host in 1 seconds" 341s time="2024-05-24T00:38:42Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 341s --- PASS: TestNew (0.00s) 341s --- PASS: TestNew/Error_when_WslPath_returns_error (28.81s) 341s --- PASS: TestNew/Success (30.64s) 341s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 341s time="2024-05-24T00:38:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38847" 341s time="2024-05-24T00:38:42Z" level=debug msg="Ready state sent to systemd" 341s time="2024-05-24T00:38:42Z" level=info msg="Daemon: connecting to Windows Agent" 341s time="2024-05-24T00:38:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 341s time="2024-05-24T00:38:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42527" 341s time="2024-05-24T00:38:43Z" level=debug msg="Building new daemon" 341s time="2024-05-24T00:38:43Z" level=debug msg="Ready state sent to systemd" 342s time="2024-05-24T00:38:43Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:38:43Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:38:43Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:38:43Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:38:51Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45163" 342s time="2024-05-24T00:38:51Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:38:51Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:38:51Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:38:51Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:38:52Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:32799" 342s time="2024-05-24T00:38:52Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:38:52Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:38:52Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:38:52Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:38:52Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44873" 342s time="2024-05-24T00:38:52Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:38:52Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:38:52Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:38:52Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:38:53Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40591" 342s time="2024-05-24T00:38:53Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:38:53Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:38:53Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:38:53Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:38:53Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38847" 342s time="2024-05-24T00:38:53Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:38:53Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:38:53Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:38:53Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:38:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37445" 342s time="2024-05-24T00:38:55Z" level=debug msg="Ready state sent to systemd" 342s time="2024-05-24T00:38:55Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:38:55Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:38:58Z" level=debug msg="Ready state sent to systemd" 342s time="2024-05-24T00:38:58Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:38:58Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:38:58Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:38:58Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:38:58Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:38:58Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:38:58Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:38:58Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:38:58Z" level=info msg="Stopping active requests." 342s time="2024-05-24T00:38:58Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:38:58Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:38:58Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:38:58Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:38:58Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:38:58Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:58Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:58Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s === CONT TestServe/Error_because_the_notifier_returns_an_error 342s time="2024-05-24T00:38:58Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42465" 342s time="2024-05-24T00:38:58Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:38:59Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:38:59Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:38:59Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:38:59Z" level=info msg="Stopping active requests." 342s time="2024-05-24T00:38:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:59Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:38:59Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:38:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:38:59Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:38:59Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/No_connection_because_the_port_file_has_port_0 342s time="2024-05-24T00:38:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36185" 342s time="2024-05-24T00:38:59Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:00Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:00Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:00Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:00Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:00Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:39:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:39:00Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:00Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:00Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:00Z" level=debug msg="Server: sent preface messages to all streams" 342s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:00Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:00Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:00Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:00Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:39:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:39:00Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37447" 342s time="2024-05-24T00:39:00Z" level=debug msg="Building new daemon" 342s === CONT TestServe/No_connection_because_the_port_file_is_empty 342s --- PASS: TestServeAndQuit (0.00s) 342s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (27.96s) 342s --- PASS: TestServeAndQuit/Success_with_forceful_quit (46.43s) 342s --- PASS: TestServeAndQuit/Success_with_double_quit (48.24s) 342s --- PASS: TestServeAndQuit/Success_with_graceful_quit (48.39s) 342s time="2024-05-24T00:39:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44515" 342s time="2024-05-24T00:39:00Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:02Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37445" 342s time="2024-05-24T00:39:02Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:39:02Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:39:02Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:39:02Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:39:03Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42831" 342s time="2024-05-24T00:39:03Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:39:03Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:39:03Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:39:03Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:39:03Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:03Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:03Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/Success 342s time="2024-05-24T00:39:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34253" 342s time="2024-05-24T00:39:03Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:08Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:08Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:08Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:08Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:08Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:08Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:08Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:08Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:39:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42809" 342s time="2024-05-24T00:39:09Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:09Z" 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" 342s time="2024-05-24T00:39:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:09Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:09Z" 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" 342s time="2024-05-24T00:39:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:09Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 342s time="2024-05-24T00:39:09Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:09Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:09Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/Error_because_the_context_is_pre-cancelled 342s time="2024-05-24T00:39:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34111" 342s time="2024-05-24T00:39:09Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:09Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:09Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:39:10Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:10Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:10Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:10Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:10Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:39:10Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:39:10Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:10Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:10Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:10Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 342s time="2024-05-24T00:39:10Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:10Z" 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" 342s time="2024-05-24T00:39:10Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:10Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:10Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:10Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/No_connection_because_there_is_no_server 342s time="2024-05-24T00:39:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34729" 342s time="2024-05-24T00:39:10Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40553" 342s time="2024-05-24T00:39:10Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:10Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:10Z" 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" 342s time="2024-05-24T00:39:10Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:10Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:10Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:10Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:10Z" 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" 342s time="2024-05-24T00:39:10Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:10Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 342s time="2024-05-24T00:39:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42967" 342s time="2024-05-24T00:39:10Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:11Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:11Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:11Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:11Z" 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" 342s time="2024-05-24T00:39:11Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:11Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/No_connection_because_there_are_no_certificates 342s time="2024-05-24T00:39:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38019" 342s time="2024-05-24T00:39:11Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:13Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:13Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42809" 342s time="2024-05-24T00:39:13Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:39:13Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:39:13Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:39:13Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:39:15Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:15Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:39:15Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:15Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:15Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:15Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:15Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:15Z" level=debug msg="All connections have now ended." 342s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 342s time="2024-05-24T00:39:15Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:15Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:15Z" level=info msg="Stopping active requests." 342s time="2024-05-24T00:39:15Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:15Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:15Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:15Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:15Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:39:15Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:39:15Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:15Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34253" 342s time="2024-05-24T00:39:15Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:39:15Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:39:15Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:39:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38491" 342s time="2024-05-24T00:39:15Z" level=debug msg="Building new daemon" 342s time="2024-05-24T00:39:15Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:16Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:16Z" 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" 342s time="2024-05-24T00:39:16Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:16Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:17Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40553" 342s time="2024-05-24T00:39:17Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:39:17Z" 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:40553: connect: connection refused\"" 342s time="2024-05-24T00:39:17Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:17Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:17Z" 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" 342s time="2024-05-24T00:39:17Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:17Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:17Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:17Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:17Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:17Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:17Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:17Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34729" 342s time="2024-05-24T00:39:17Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file894830267/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 342s time="2024-05-24T00:39:17Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:17Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:39:17Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:39:17Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:39:17Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:39:17Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:17Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:17Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:18Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38019" 342s time="2024-05-24T00:39:18Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates2108070787/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 342s time="2024-05-24T00:39:18Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:18Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:18Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:18Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:19Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:19Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:19Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:39:19Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:19Z" level=debug msg="All connections have now ended." 342s time="2024-05-24T00:39:19Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:19Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:19Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:39:19Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:19Z" 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_exist3467097558/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3467097558/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 342s time="2024-05-24T00:39:19Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:39:20Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:39:20Z" 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_exist3467097558/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3467097558/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 342s time="2024-05-24T00:39:20Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:39:21Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:39:21Z" level=info msg="Waiting for active requests to close." 342s time="2024-05-24T00:39:21Z" level=debug msg="All connections have now ended." 342s --- PASS: TestServe (0.00s) 342s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (20.89s) 342s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (11.18s) 342s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (29.41s) 342s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (10.61s) 342s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (10.29s) 342s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (10.93s) 342s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (6.09s) 342s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (6.70s) 342s --- PASS: TestServe/No_connection_because_there_is_no_server (7.16s) 342s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (7.54s) 342s --- PASS: TestServe/No_connection_because_there_are_no_certificates (7.08s) 342s --- PASS: TestServe/Success (15.32s) 342s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (5.19s) 342s time="2024-05-24T00:40:04Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:04Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:04Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:04Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:40:04Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:40:04Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:40:04Z" 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_connection_dropped3364944468/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped3364944468/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 342s time="2024-05-24T00:40:04Z" level=info msg="Reconnecting to Windows host in 1 seconds" 342s time="2024-05-24T00:40:04Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:40:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46785" 342s time="2024-05-24T00:40:05Z" level=info msg="Daemon: connecting to Windows Agent" 342s time="2024-05-24T00:40:05Z" level=debug msg="Updated systemd status to \"Connecting\"" 342s time="2024-05-24T00:40:06Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46785" 342s time="2024-05-24T00:40:06Z" level=info msg="Daemon: completed connection to Windows Agent" 342s time="2024-05-24T00:40:06Z" level=debug msg="Updated systemd status to \"Connected\"" 342s time="2024-05-24T00:40:06Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 342s time="2024-05-24T00:40:06Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 342s time="2024-05-24T00:40:07Z" level=debug msg="Server: sent preface messages to all streams" 342s time="2024-05-24T00:40:07Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:40:07Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:40:07Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:40:07Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:07Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:07Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:40:07Z" level=info msg="Stopping daemon requested." 342s time="2024-05-24T00:40:07Z" level=info msg="Stopping active requests." 342s time="2024-05-24T00:40:07Z" level=warning msg="Daemon: disconnected from Windows host" 342s time="2024-05-24T00:40:07Z" level=info msg="Reconnecting to Windows host in 2 seconds" 342s time="2024-05-24T00:40:07Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 342s time="2024-05-24T00:40:07Z" level=debug msg="Updated systemd status to \"Stopped\"" 342s time="2024-05-24T00:40:07Z" level=debug msg="All connections have now ended." 342s --- PASS: TestReconnection (0.00s) 342s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (47.62s) 342s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (35.75s) 342s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (115.74s) 342s PASS 342s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 116.532s 342s === RUN TestConnect 342s === PAUSE TestConnect 342s === RUN TestSendAndRecv 342s --- PASS: TestSendAndRecv (1.16s) 342s === RUN TestServe 342s === PAUSE TestServe 342s === RUN TestStop 342s === PAUSE TestStop 342s === RUN TestWithProMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithProMock (0.00s) 342s === RUN TestWithWslPathMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithWslPathMock (0.00s) 342s === RUN TestWithWslInfoMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithWslInfoMock (0.00s) 342s === RUN TestWithCmdExeMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithCmdExeMock (0.00s) 342s === CONT TestConnect 342s === RUN TestConnect/Success 342s === PAUSE TestConnect/Success 342s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 342s === CONT TestServe 342s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 342s === CONT TestConnect/Success 342s === CONT TestStop 342s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 342s time="2024-05-24T00:38:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46877" 342s time="2024-05-24T00:38:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36945" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: Connected ready" 342s time="2024-05-24T00:38:31Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 342s --- PASS: TestConnect (0.00s) 342s --- PASS: TestConnect/Success (0.48s) 342s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.21s) 342s time="2024-05-24T00:38:41Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:41Z" level=warning msg="Streamserver: could not gather info after command completion: could not obtain pro status: pro status: /usr/bin/bash: error: context canceled.\n Stdout: \n Stderr: " 342s time="2024-05-24T00:38:41Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:41Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:41Z" level=warning msg="Streamserver: could not stream back info after command completion" 342s --- PASS: TestStop (26.93s) 342s time="2024-05-24T00:38:57Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:57Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 342s time="2024-05-24T00:38:57Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 342s --- PASS: TestServe (43.34s) 342s PASS 342s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 46.376s 342s === RUN TestInfo 342s === PAUSE TestInfo 342s === RUN TestWslDistroName 342s === PAUSE TestWslDistroName 342s === RUN TestUserProfileDir 342s === PAUSE TestUserProfileDir 342s === RUN TestProStatus 342s === PAUSE TestProStatus 342s === RUN TestProAttach 342s === PAUSE TestProAttach 342s === RUN TestProDetach 342s === PAUSE TestProDetach 342s === RUN TestLandscapeEnable 342s === PAUSE TestLandscapeEnable 342s === RUN TestWindowsHostAddress 342s === PAUSE TestWindowsHostAddress 342s === RUN TestLandscapeDisable 342s === PAUSE TestLandscapeDisable 342s === RUN TestRealBackend 342s === PAUSE TestRealBackend 342s === RUN TestWithProMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithProMock (0.00s) 342s === RUN TestWithLandscapeConfigMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithLandscapeConfigMock (0.00s) 342s === RUN TestWithWslPathMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithWslPathMock (0.00s) 342s === RUN TestWithWslInfoMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithWslInfoMock (0.00s) 342s === RUN TestWithCmdExeMock 342s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 342s --- SKIP: TestWithCmdExeMock (0.00s) 342s === CONT TestInfo 342s === RUN TestInfo/Success 342s === PAUSE TestInfo/Success 342s === RUN TestInfo/Error_when_WslDistroName_fails 342s === PAUSE TestInfo/Error_when_WslDistroName_fails 342s === RUN TestInfo/Error_when_pro_status_command_fails 342s === PAUSE TestInfo/Error_when_pro_status_command_fails 342s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 342s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 342s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 342s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 342s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 342s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 342s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 342s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 342s === CONT TestInfo/Success 342s === CONT TestUserProfileDir 342s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 342s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 342s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 342s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 342s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 342s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 342s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 342s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 342s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 342s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 342s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 342s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 342s === RUN TestUserProfileDir/Error_on_cmd.exe_error 342s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 342s === RUN TestUserProfileDir/Error_on_wslpath_error 342s === PAUSE TestUserProfileDir/Error_on_wslpath_error 342s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 342s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 342s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 342s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 342s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 342s system_test.go:205: Removing default proc/mounts 342s === CONT TestRealBackend 342s --- PASS: TestRealBackend (0.00s) 342s === CONT TestLandscapeDisable 342s === RUN TestLandscapeDisable/Success 342s === PAUSE TestLandscapeDisable/Success 342s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 342s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 342s === CONT TestLandscapeDisable/Success 342s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 342s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 342s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 342s === CONT TestWindowsHostAddress 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 342s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 342s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 342s === RUN TestWindowsHostAddress/Success_without_NAT 342s === PAUSE TestWindowsHostAddress/Success_without_NAT 342s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 342s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 342s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 342s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 342s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 342s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 342s === CONT TestLandscapeEnable 342s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 342s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 342s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 342s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 342s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 342s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 342s === RUN TestLandscapeEnable/Success 342s === PAUSE TestLandscapeEnable/Success 342s === RUN TestLandscapeEnable/Success_overriding_computer_title 342s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 342s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 342s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 342s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 342s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 342s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 342s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 342s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 342s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 342s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 342s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 342s === CONT TestProAttach 342s === RUN TestProAttach/success 342s === PAUSE TestProAttach/success 342s === RUN TestProAttach/error_on_'pro_attach'_error 342s === PAUSE TestProAttach/error_on_'pro_attach'_error 342s === CONT TestProDetach 342s === RUN TestProDetach/success_on_unattached_distro 342s === PAUSE TestProDetach/success_on_unattached_distro 342s === RUN TestProDetach/success_on_attached_distro 342s === PAUSE TestProDetach/success_on_attached_distro 342s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 342s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 342s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 342s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 342s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 342s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 342s === CONT TestProStatus 342s === RUN TestProStatus/success_on_unattached_distro 342s === PAUSE TestProStatus/success_on_unattached_distro 342s === RUN TestProStatus/success_on_attached_distro 342s === PAUSE TestProStatus/success_on_attached_distro 342s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 342s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 342s === RUN TestProStatus/error_on_'pro_attach'_error 342s === PAUSE TestProStatus/error_on_'pro_attach'_error 342s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 342s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 342s === CONT TestUserProfileDir/Error_on_wslpath_error 342s === CONT TestUserProfileDir/Error_on_cmd.exe_error 342s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 342s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 342s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 342s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 342s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 342s system_test.go:205: Removing default proc/mounts 342s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 342s === CONT TestWslDistroName 342s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 342s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 342s === RUN TestWslDistroName/Success_using_wslpath 342s === PAUSE TestWslDistroName/Success_using_wslpath 342s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 342s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 342s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 342s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 342s === CONT TestInfo/Error_when_pro_status_command_fails 342s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 342s === CONT TestInfo/Error_when_WslDistroName_fails 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 342s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 342s === CONT TestProAttach/success 342s --- PASS: TestLandscapeDisable (0.00s) 342s --- PASS: TestLandscapeDisable/Success (22.61s) 342s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (8.46s) 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 342s time="2024-05-24T00:38:46Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 342s --- PASS: TestInfo (0.00s) 342s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (15.89s) 342s --- PASS: TestInfo/Success (17.61s) 342s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (18.07s) 342s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (19.23s) 342s --- PASS: TestInfo/Error_when_pro_status_command_fails (8.30s) 342s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (6.93s) 342s --- PASS: TestInfo/Error_when_WslDistroName_fails (9.26s) 342s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 342s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 342s === CONT TestWindowsHostAddress/Success_without_NAT 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 342s --- PASS: TestUserProfileDir (0.00s) 342s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 342s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (1.62s) 342s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.37s) 342s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (24.66s) 342s --- PASS: TestUserProfileDir/Error_on_wslpath_error (26.10s) 342s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (27.53s) 342s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (14.52s) 342s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (20.99s) 342s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (21.01s) 342s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (23.22s) 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 342s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 342s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 342s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 342s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 342s === CONT TestProDetach/success_on_unattached_distro 342s === CONT TestProStatus/success_on_unattached_distro 342s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 342s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 342s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 342s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 342s time="2024-05-24T00:39:00Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 342s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 342s === CONT TestLandscapeEnable/Success_overriding_computer_title 342s time="2024-05-24T00:39:02Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 342s === CONT TestLandscapeEnable/Success 342s --- PASS: TestWindowsHostAddress (0.00s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (9.39s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (9.24s) 342s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (7.83s) 342s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (6.47s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (6.85s) 342s --- PASS: TestWindowsHostAddress/Success_without_NAT (7.33s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (6.55s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (5.55s) 342s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (5.05s) 342s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (7.09s) 342s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 342s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 342s === CONT TestProAttach/error_on_'pro_attach'_error 342s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 342s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 342s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 342s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 342s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 342s === NAME TestLandscapeEnable/Success_overriding_computer_title 342s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 342s === CONT TestProDetach/success_on_attached_distro 342s === CONT TestProStatus/error_on_'pro_attach'_error 342s === NAME TestLandscapeEnable/Success 342s system_test.go:456: testdata/TestLandscapeEnable/golden/success 342s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 342s --- PASS: TestProAttach (0.00s) 342s --- PASS: TestProAttach/success (10.60s) 342s --- PASS: TestProAttach/error_on_'pro_attach'_error (5.59s) 342s === CONT TestProStatus/success_on_attached_distro 342s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 342s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 342s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 342s === CONT TestWslDistroName/Success_using_wslpath 342s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 342s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 342s --- PASS: TestLandscapeEnable (0.00s) 342s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (9.26s) 342s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 342s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.00s) 342s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 342s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (4.88s) 342s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (5.39s) 342s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (5.07s) 342s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (7.07s) 342s --- PASS: TestLandscapeEnable/Success (5.18s) 342s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (10.10s) 342s --- PASS: TestProDetach (0.00s) 342s --- PASS: TestProDetach/success_on_unattached_distro (4.75s) 342s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (5.08s) 342s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (4.61s) 342s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (4.88s) 342s --- PASS: TestProDetach/success_on_attached_distro (5.05s) 342s --- PASS: TestProStatus (0.07s) 342s --- PASS: TestProStatus/success_on_unattached_distro (5.36s) 342s --- PASS: TestProStatus/error_on_'pro_attach'_error (4.29s) 342s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (4.83s) 342s --- PASS: TestProStatus/success_on_attached_distro (4.44s) 342s --- PASS: TestWslDistroName (0.00s) 342s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 342s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (4.16s) 342s --- PASS: TestWslDistroName/Success_using_wslpath (2.76s) 342s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (3.82s) 342s PASS 342s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 61.576s 342s autopkgtest [00:40:08]: test command1: -----------------------] 346s autopkgtest [00:40:12]: test command1: - - - - - - - - - - results - - - - - - - - - - 346s command1 PASS 349s autopkgtest [00:40:15]: @@@@@@@@@@@@@@@@@@@@ summary 349s command1 PASS