0s autopkgtest [12:02:30]: starting date and time: 2024-06-28 12:02:30+0000 0s autopkgtest [12:02:30]: git checkout: 85adf9a1 setup-testbed: prevent /tmp from getting a tmpfs mount 0s autopkgtest [12:02:30]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.mqu4uf6v/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.3.1~24.04' -- lxd -r lxd-armhf-10.145.243.9 lxd-armhf-10.145.243.9:autopkgtest/ubuntu/noble/armhf 23s autopkgtest [12:02:53]: testbed dpkg architecture: armhf 25s autopkgtest [12:02:55]: testbed apt version: 2.7.14build2 25s autopkgtest [12:02:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 33s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 33s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [8180 B] 33s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [29.5 kB] 33s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [11.6 kB] 33s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [32.3 kB] 33s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 33s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1176 B] 33s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 33s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [44.3 kB] 33s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 33s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 35s Fetched 403 kB in 1s (529 kB/s) 35s Reading package lists... 52s tee: /proc/self/fd/2: Permission denied 69s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 69s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 69s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 69s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 70s Reading package lists... 70s Reading package lists... 71s Building dependency tree... 71s Reading state information... 71s Calculating upgrade... 72s The following packages will be upgraded: 72s dracut-install ubuntu-pro-client ubuntu-pro-client-l10n 72s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 72s Need to get 284 kB of archives. 72s After this operation, 0 B of additional disk space will be used. 72s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 32.3.1~24.04 [19.4 kB] 72s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 32.3.1~24.04 [231 kB] 72s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main armhf dracut-install armhf 060+5-1ubuntu3.1 [34.1 kB] 73s Fetched 284 kB in 0s (585 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 ... 57905 files and directories currently installed.) 73s Preparing to unpack .../ubuntu-pro-client-l10n_32.3.1~24.04_armhf.deb ... 73s Unpacking ubuntu-pro-client-l10n (32.3.1~24.04) over (32.3~24.04) ... 73s Preparing to unpack .../ubuntu-pro-client_32.3.1~24.04_armhf.deb ... 73s Unpacking ubuntu-pro-client (32.3.1~24.04) over (32.3~24.04) ... 73s Preparing to unpack .../dracut-install_060+5-1ubuntu3.1_armhf.deb ... 73s Unpacking dracut-install (060+5-1ubuntu3.1) over (060+5-1ubuntu3) ... 73s Setting up dracut-install (060+5-1ubuntu3.1) ... 73s Setting up ubuntu-pro-client (32.3.1~24.04) ... 73s Installing new version of config file /etc/apparmor.d/ubuntu_pro_esm_cache ... 73s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 73s 73s apparmor_parser: Unable to replace "apt_methods". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 73s 73s apparmor_parser: Unable to replace "ubuntu_pro_esm_cache". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 73s 75s Setting up ubuntu-pro-client-l10n (32.3.1~24.04) ... 75s Processing triggers for man-db (2.12.0-4build2) ... 75s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 78s autopkgtest [12:03:48]: rebooting testbed after setup commands that affected boot 118s autopkgtest [12:04:28]: testbed running kernel: Linux 6.5.0-41-generic #41~22.04.2-Ubuntu SMP PREEMPT_DYNAMIC Mon Jun 3 16:28:24 UTC 2 144s autopkgtest [12:04:54]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 154s Get:1 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (dsc) [1420 B] 154s Get:2 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (tar) [1762 kB] 154s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 154s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 154s gpgv: Can't check signature: No public key 154s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 154s autopkgtest [12:05:04]: testing package wsl-pro-service version 0.1.4 156s autopkgtest [12:05:06]: build not needed 159s autopkgtest [12:05:09]: test command1: preparing testbed 169s Reading package lists... 169s Building dependency tree... 169s Reading state information... 170s Starting pkgProblemResolver with broken count: 0 170s Starting 2 pkgProblemResolver with broken count: 0 170s 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 171s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 171s Need to get 95.3 MB/95.3 MB of archives. 171s After this operation, 377 MB of additional disk space will be used. 171s Get:1 /tmp/autopkgtest.MeHdHe/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [764 B] 171s 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.2 [19.1 kB] 171s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main armhf linux-libc-dev armhf 6.8.0-36.36 [1584 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.2 [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] 172s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-23ubuntu4 [1034 B] 172s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 172s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 172s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240412-0ubuntu1 [39.0 kB] 172s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240412-0ubuntu1 [125 kB] 172s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240412-0ubuntu1 [7816 B] 172s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240412-0ubuntu1 [2942 kB] 172s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240412-0ubuntu1 [1154 kB] 172s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-23ubuntu4 [899 kB] 172s 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] 172s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf libdw1t64 armhf 0.190-1.1build4 [235 kB] 172s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5build2 [42.3 kB] 172s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1build6 [116 kB] 172s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu2 [800 kB] 172s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 172s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 172s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.14.1ubuntu5 [869 kB] 172s 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] 173s Get:51 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.22-go armhf 1.22.2-2 [23.7 MB] 174s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.22~2build1 [5078 B] 174s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.22~2build1 [43.9 kB] 174s Get:54 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 174s Fetched 95.3 MB in 3s (29.7 MB/s) 174s Selecting previously unselected package m4. 174s (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 ... 57905 files and directories currently installed.) 174s Preparing to unpack .../00-m4_1.4.19-4build1_armhf.deb ... 174s Unpacking m4 (1.4.19-4build1) ... 174s Selecting previously unselected package autoconf. 174s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 174s Unpacking autoconf (2.71-3) ... 174s Selecting previously unselected package autotools-dev. 174s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 174s Unpacking autotools-dev (20220109.1) ... 174s Selecting previously unselected package automake. 174s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 174s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 174s Selecting previously unselected package autopoint. 174s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 174s Unpacking autopoint (0.21-14ubuntu2) ... 174s Selecting previously unselected package libc-dev-bin. 174s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu8.2_armhf.deb ... 174s Unpacking libc-dev-bin (2.39-0ubuntu8.2) ... 174s Selecting previously unselected package linux-libc-dev:armhf. 174s Preparing to unpack .../06-linux-libc-dev_6.8.0-36.36_armhf.deb ... 174s Unpacking linux-libc-dev:armhf (6.8.0-36.36) ... 175s Selecting previously unselected package libcrypt-dev:armhf. 175s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4build1_armhf.deb ... 175s Unpacking libcrypt-dev:armhf (1:4.4.36-4build1) ... 175s Selecting previously unselected package rpcsvc-proto. 175s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu7_armhf.deb ... 175s Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ... 175s Selecting previously unselected package libc6-dev:armhf. 175s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu8.2_armhf.deb ... 175s Unpacking libc6-dev:armhf (2.39-0ubuntu8.2) ... 175s Selecting previously unselected package gcc-13-base:armhf. 175s Preparing to unpack .../10-gcc-13-base_13.2.0-23ubuntu4_armhf.deb ... 175s Unpacking gcc-13-base:armhf (13.2.0-23ubuntu4) ... 175s Selecting previously unselected package libisl23:armhf. 175s Preparing to unpack .../11-libisl23_0.26-3build1_armhf.deb ... 175s Unpacking libisl23:armhf (0.26-3build1) ... 175s Selecting previously unselected package libmpc3:armhf. 175s Preparing to unpack .../12-libmpc3_1.3.1-1build1_armhf.deb ... 175s Unpacking libmpc3:armhf (1.3.1-1build1) ... 175s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 175s Preparing to unpack .../13-cpp-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 175s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 175s Selecting previously unselected package cpp-13. 175s Preparing to unpack .../14-cpp-13_13.2.0-23ubuntu4_armhf.deb ... 175s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 175s Selecting previously unselected package cpp-arm-linux-gnueabihf. 175s Preparing to unpack .../15-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 175s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 175s Selecting previously unselected package cpp. 175s Preparing to unpack .../16-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 175s Unpacking cpp (4:13.2.0-7ubuntu1) ... 175s Selecting previously unselected package libcc1-0:armhf. 175s Preparing to unpack .../17-libcc1-0_14-20240412-0ubuntu1_armhf.deb ... 175s Unpacking libcc1-0:armhf (14-20240412-0ubuntu1) ... 175s Selecting previously unselected package libgomp1:armhf. 175s Preparing to unpack .../18-libgomp1_14-20240412-0ubuntu1_armhf.deb ... 175s Unpacking libgomp1:armhf (14-20240412-0ubuntu1) ... 175s Selecting previously unselected package libatomic1:armhf. 175s Preparing to unpack .../19-libatomic1_14-20240412-0ubuntu1_armhf.deb ... 175s Unpacking libatomic1:armhf (14-20240412-0ubuntu1) ... 175s Selecting previously unselected package libasan8:armhf. 175s Preparing to unpack .../20-libasan8_14-20240412-0ubuntu1_armhf.deb ... 175s Unpacking libasan8:armhf (14-20240412-0ubuntu1) ... 175s Selecting previously unselected package libubsan1:armhf. 175s Preparing to unpack .../21-libubsan1_14-20240412-0ubuntu1_armhf.deb ... 175s Unpacking libubsan1:armhf (14-20240412-0ubuntu1) ... 175s Selecting previously unselected package libgcc-13-dev:armhf. 175s Preparing to unpack .../22-libgcc-13-dev_13.2.0-23ubuntu4_armhf.deb ... 175s Unpacking libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 176s Preparing to unpack .../23-gcc-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package gcc-13. 176s Preparing to unpack .../24-gcc-13_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package gcc-arm-linux-gnueabihf. 176s Preparing to unpack .../25-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 176s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package gcc. 176s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 176s Unpacking gcc (4:13.2.0-7ubuntu1) ... 176s Selecting previously unselected package libstdc++-13-dev:armhf. 176s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 176s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 176s Preparing to unpack .../28-g++-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 176s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 177s Selecting previously unselected package g++-13. 177s Preparing to unpack .../29-g++-13_13.2.0-23ubuntu4_armhf.deb ... 177s Unpacking g++-13 (13.2.0-23ubuntu4) ... 177s Selecting previously unselected package g++-arm-linux-gnueabihf. 177s Preparing to unpack .../30-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 177s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package g++. 177s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 177s Unpacking g++ (4:13.2.0-7ubuntu1) ... 177s Selecting previously unselected package build-essential. 177s Preparing to unpack .../32-build-essential_12.10ubuntu1_armhf.deb ... 177s Unpacking build-essential (12.10ubuntu1) ... 177s Selecting previously unselected package libdebhelper-perl. 177s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 177s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 177s Selecting previously unselected package libtool. 177s Preparing to unpack .../34-libtool_2.4.7-7build1_all.deb ... 177s Unpacking libtool (2.4.7-7build1) ... 177s Selecting previously unselected package dh-autoreconf. 177s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 177s Unpacking dh-autoreconf (20) ... 177s Selecting previously unselected package libarchive-zip-perl. 177s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 177s Unpacking libarchive-zip-perl (1.68-1) ... 177s Selecting previously unselected package libsub-override-perl. 177s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 177s Unpacking libsub-override-perl (0.10-1) ... 177s Selecting previously unselected package libfile-stripnondeterminism-perl. 177s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 177s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 177s Selecting previously unselected package dh-strip-nondeterminism. 177s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 177s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 177s Selecting previously unselected package libdw1t64:armhf. 177s Preparing to unpack .../40-libdw1t64_0.190-1.1build4_armhf.deb ... 177s Unpacking libdw1t64:armhf (0.190-1.1build4) ... 177s Selecting previously unselected package debugedit. 177s Preparing to unpack .../41-debugedit_1%3a5.0-5build2_armhf.deb ... 177s Unpacking debugedit (1:5.0-5build2) ... 177s Selecting previously unselected package dwz. 177s Preparing to unpack .../42-dwz_0.15-1build6_armhf.deb ... 177s Unpacking dwz (0.15-1build6) ... 177s Selecting previously unselected package gettext. 177s Preparing to unpack .../43-gettext_0.21-14ubuntu2_armhf.deb ... 177s Unpacking gettext (0.21-14ubuntu2) ... 177s Selecting previously unselected package intltool-debian. 177s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 177s Unpacking intltool-debian (0.35.0+20060710.6) ... 177s Selecting previously unselected package po-debconf. 177s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 177s Unpacking po-debconf (1.0.21+nmu1) ... 177s Selecting previously unselected package debhelper. 177s Preparing to unpack .../46-debhelper_13.14.1ubuntu5_all.deb ... 177s Unpacking debhelper (13.14.1ubuntu5) ... 177s Selecting previously unselected package dh-apport. 177s Preparing to unpack .../47-dh-apport_2.28.1-0ubuntu3_all.deb ... 177s Unpacking dh-apport (2.28.1-0ubuntu3) ... 177s Selecting previously unselected package golang-1.22-src. 177s Preparing to unpack .../48-golang-1.22-src_1.22.2-2_all.deb ... 177s Unpacking golang-1.22-src (1.22.2-2) ... 180s Selecting previously unselected package golang-1.22-go. 180s Preparing to unpack .../49-golang-1.22-go_1.22.2-2_armhf.deb ... 180s Unpacking golang-1.22-go (1.22.2-2) ... 180s Selecting previously unselected package golang-src. 180s Preparing to unpack .../50-golang-src_2%3a1.22~2build1_all.deb ... 180s Unpacking golang-src (2:1.22~2build1) ... 180s Selecting previously unselected package golang-go:armhf. 180s Preparing to unpack .../51-golang-go_2%3a1.22~2build1_armhf.deb ... 180s Unpacking golang-go:armhf (2:1.22~2build1) ... 180s Selecting previously unselected package dh-golang. 180s Preparing to unpack .../52-dh-golang_1.62_all.deb ... 180s Unpacking dh-golang (1.62) ... 181s Selecting previously unselected package autopkgtest-satdep. 181s Preparing to unpack .../53-1-autopkgtest-satdep.deb ... 181s Unpacking autopkgtest-satdep (0) ... 181s Setting up libarchive-zip-perl (1.68-1) ... 181s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 181s Setting up linux-libc-dev:armhf (6.8.0-36.36) ... 181s Setting up m4 (1.4.19-4build1) ... 181s Setting up libgomp1:armhf (14-20240412-0ubuntu1) ... 181s Setting up dh-apport (2.28.1-0ubuntu3) ... 181s Setting up libdw1t64:armhf (0.190-1.1build4) ... 181s Setting up autotools-dev (20220109.1) ... 181s Setting up rpcsvc-proto (1.4.2-0ubuntu7) ... 181s Setting up gcc-13-base:armhf (13.2.0-23ubuntu4) ... 181s Setting up golang-1.22-src (1.22.2-2) ... 181s Setting up libmpc3:armhf (1.3.1-1build1) ... 181s Setting up libatomic1:armhf (14-20240412-0ubuntu1) ... 181s Setting up autopoint (0.21-14ubuntu2) ... 181s Setting up autoconf (2.71-3) ... 181s Setting up libubsan1:armhf (14-20240412-0ubuntu1) ... 181s Setting up dwz (0.15-1build6) ... 181s Setting up libcrypt-dev:armhf (1:4.4.36-4build1) ... 181s Setting up libasan8:armhf (14-20240412-0ubuntu1) ... 181s Setting up debugedit (1:5.0-5build2) ... 181s Setting up libsub-override-perl (0.10-1) ... 181s Setting up libisl23:armhf (0.26-3build1) ... 181s Setting up libc-dev-bin (2.39-0ubuntu8.2) ... 181s Setting up golang-src (2:1.22~2build1) ... 181s Setting up libcc1-0:armhf (14-20240412-0ubuntu1) ... 181s Setting up automake (1:1.16.5-1.3ubuntu1) ... 181s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 181s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 181s Setting up gettext (0.21-14ubuntu2) ... 181s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 181s Setting up golang-1.22-go (1.22.2-2) ... 181s Setting up intltool-debian (0.35.0+20060710.6) ... 181s Setting up dh-strip-nondeterminism (1.13.1-1) ... 181s Setting up libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 181s Setting up libc6-dev:armhf (2.39-0ubuntu8.2) ... 181s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 181s Setting up libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 181s Setting up cpp-13 (13.2.0-23ubuntu4) ... 181s Setting up golang-go:armhf (2:1.22~2build1) ... 181s Setting up po-debconf (1.0.21+nmu1) ... 181s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 181s Setting up gcc-13 (13.2.0-23ubuntu4) ... 181s Setting up cpp (4:13.2.0-7ubuntu1) ... 181s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 181s Setting up g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 181s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 181s Setting up g++-13 (13.2.0-23ubuntu4) ... 181s Setting up libtool (2.4.7-7build1) ... 181s Setting up gcc (4:13.2.0-7ubuntu1) ... 181s Setting up dh-autoreconf (20) ... 181s Setting up g++ (4:13.2.0-7ubuntu1) ... 181s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 181s Setting up build-essential (12.10ubuntu1) ... 181s Setting up debhelper (13.14.1ubuntu5) ... 181s Setting up dh-golang (1.62) ... 181s Setting up autopkgtest-satdep (0) ... 181s Processing triggers for man-db (2.12.0-4build2) ... 182s Processing triggers for install-info (7.1-3build2) ... 182s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 192s (Reading database ... 76044 files and directories currently installed.) 192s Removing autopkgtest-satdep (0) ... 198s autopkgtest [12:05:48]: test command1: ./debian/tests/test 198s autopkgtest [12:05:48]: test command1: [----------------------- 219s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 219s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 219s === RUN TestRunSignal 219s === RUN TestRunSignal/Send_SIGINT_exits 219s INFO Starting WSL Pro Service version Dev 219s === RUN TestRunSignal/Send_SIGTERM_exits 219s INFO Starting WSL Pro Service version Dev 219s --- PASS: TestRunSignal (0.20s) 219s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 219s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 219s === RUN TestRun 219s === PAUSE TestRun 219s === CONT TestRun 219s === RUN TestRun/Run_and_exit_successfully 219s === PAUSE TestRun/Run_and_exit_successfully 219s === RUN TestRun/Run_and_return_error 219s === PAUSE TestRun/Run_and_return_error 219s === RUN TestRun/Run_and_return_usage_error 219s === PAUSE TestRun/Run_and_return_usage_error 219s === RUN TestRun/Run_and_usage_error_only_does_not_fail 219s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 219s === CONT TestRun/Run_and_exit_successfully 219s === CONT TestRun/Run_and_return_usage_error 219s === CONT TestRun/Run_and_usage_error_only_does_not_fail 219s === CONT TestRun/Run_and_return_error 219s INFO Starting WSL Pro Service version Dev 219s INFO Starting WSL Pro Service version Dev 219s INFO Starting WSL Pro Service version Dev 219s INFO Starting WSL Pro Service version Dev 219s ERROR context.Background Error requested 219s ERROR context.Background Error requested 219s --- PASS: TestRun (0.00s) 219s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 219s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 219s --- PASS: TestRun/Run_and_return_error (0.10s) 219s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 219s PASS 219s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.326s 219s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 258s === RUN TestHelp 258s --- PASS: TestHelp (0.00s) 258s === RUN TestCompletion 258s --- PASS: TestCompletion (0.59s) 258s === RUN TestVersion 258s --- PASS: TestVersion (1.11s) 258s === RUN TestNoUsageError 258s --- PASS: TestNoUsageError (1.72s) 258s === RUN TestUsageError 258s === PAUSE TestUsageError 258s === RUN TestCanQuitWhenExecute 258s === PAUSE TestCanQuitWhenExecute 258s === RUN TestCanQuitTwice 258s === PAUSE TestCanQuitTwice 258s === RUN TestAppCanQuitWithoutExecute 258s === PAUSE TestAppCanQuitWithoutExecute 258s === RUN TestAppRunFailsOnComponentsCreationAndQuit 258s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 258s === RUN TestAppGetRootCmd 258s === PAUSE TestAppGetRootCmd 258s === RUN TestWithProMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithProMock (0.00s) 258s === RUN TestWithWslPathMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithWslPathMock (0.00s) 258s === RUN TestWithWslInfoMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithWslInfoMock (0.00s) 258s === RUN TestWithCmdExeMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithCmdExeMock (0.00s) 258s === CONT TestUsageError 258s === CONT TestAppCanQuitWithoutExecute 258s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 258s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 258s === CONT TestAppGetRootCmd 258s === CONT TestCanQuitTwice 258s === CONT TestAppRunFailsOnComponentsCreationAndQuit 258s === CONT TestCanQuitWhenExecute 258s time="2024-06-28T12:06:13Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 258s time="2024-06-28T12:06:13Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:75 New() Building new daemon" 258s time="2024-06-28T12:06:13Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41987" 258s time="2024-06-28T12:06:13Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 258s time="2024-06-28T12:06:13Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:75 New() Building new daemon" 258s --- PASS: TestUsageError (1.15s) 258s --- PASS: TestAppGetRootCmd (1.91s) 258s time="2024-06-28T12:06:30Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 258s time="2024-06-28T12:06:30Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 258s time="2024-06-28T12:06:31Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:31Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:32Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 258s time="2024-06-28T12:06:32Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:32Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:38Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (25.30s) 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:34283" 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 258s time="2024-06-28T12:06:38Z" level=warning msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:184 (*Daemon).Serve.func1() 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:34283: connect: connection refused\"" 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 258s time="2024-06-28T12:06:38Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:38Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:38Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s --- PASS: TestCanQuitWhenExecute (25.94s) 258s time="2024-06-28T12:06:41Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:36453" 258s time="2024-06-28T12:06:41Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 258s time="2024-06-28T12:06:41Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 258s time="2024-06-28T12:06:41Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 258s time="2024-06-28T12:06:47Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/streams/server.go:141 (*Server).Serve() Server: sent preface messages to all streams" 258s time="2024-06-28T12:06:47Z" level=warning msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:184 (*Daemon).Serve.func1() Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: context canceled\ncould not receive ProAttachCmd: context canceled" 258s time="2024-06-28T12:06:47Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 258s time="2024-06-28T12:06:47Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s time="2024-06-28T12:06:47Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:47Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:47Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s time="2024-06-28T12:06:47Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.MeHdHe/build.4AZ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 258s time="2024-06-28T12:06:47Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.MeHdHe/build.4AZ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 258s time="2024-06-28T12:06:47Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.MeHdHe/build.4AZ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 258s time="2024-06-28T12:06:48Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 258s time="2024-06-28T12:06:48Z" level=info msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 258s time="2024-06-28T12:06:48Z" level=debug msg="/tmp/autopkgtest.MeHdHe/build.4AZ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 258s --- PASS: TestCanQuitTwice (35.07s) 258s PASS 258s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 38.529s 258s === RUN TestApplyProToken 258s === PAUSE TestApplyProToken 258s === RUN TestApplyLandscapeConfig 258s === PAUSE TestApplyLandscapeConfig 258s === RUN TestWithProMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithProMock (0.00s) 258s === RUN TestWithLandscapeConfigMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithLandscapeConfigMock (0.00s) 258s === RUN TestWithWslPathMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithWslPathMock (0.00s) 258s === RUN TestWithWslInfoMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithWslInfoMock (0.00s) 258s === RUN TestWithCmdExeMock 258s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 258s --- SKIP: TestWithCmdExeMock (0.00s) 258s === CONT TestApplyProToken 258s === RUN TestApplyProToken/Success_attaching 258s === PAUSE TestApplyProToken/Success_attaching 258s === RUN TestApplyProToken/Success_detaching 258s === PAUSE TestApplyProToken/Success_detaching 258s === RUN TestApplyProToken/Error_calling_pro_detach 258s === CONT TestApplyLandscapeConfig 258s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 258s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 258s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 258s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 258s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 258s === PAUSE TestApplyProToken/Error_calling_pro_detach 258s === RUN TestApplyProToken/Error_calling_pro_attach 258s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 258s === PAUSE TestApplyProToken/Error_calling_pro_attach 258s === CONT TestApplyProToken/Success_attaching 258s === CONT TestApplyProToken/Error_calling_pro_attach 258s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 258s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 258s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 258s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 258s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 258s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 258s === CONT TestApplyProToken/Success_detaching 258s === CONT TestApplyProToken/Error_calling_pro_detach 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyLandscapeConfig: received config: registering" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyLandscapeConfig: received config: registering" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyProToken: Received empty token: detaching" 258s time="2024-06-28T12:06:09Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 258s --- PASS: TestApplyLandscapeConfig (0.00s) 258s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (7.95s) 258s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (8.28s) 258s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (8.52s) 258s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (10.31s) 258s --- PASS: TestApplyProToken (0.00s) 258s --- PASS: TestApplyProToken/Error_calling_pro_detach (8.15s) 258s --- PASS: TestApplyProToken/Success_detaching (8.78s) 258s --- PASS: TestApplyProToken/Success_attaching (15.14s) 258s --- PASS: TestApplyProToken/Error_calling_pro_attach (15.26s) 258s PASS 258s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 15.297s 338s === RUN TestNew 338s === PAUSE TestNew 338s === RUN TestServe 338s === PAUSE TestServe 338s === RUN TestServeAndQuit 338s === PAUSE TestServeAndQuit 338s === RUN TestReconnection 338s === PAUSE TestReconnection 338s === RUN TestWithProMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithProMock (0.00s) 338s === RUN TestWithWslPathMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslPathMock (0.00s) 338s === RUN TestWithWslInfoMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslInfoMock (0.00s) 338s === RUN TestWithCmdExeMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithCmdExeMock (0.00s) 338s === CONT TestNew 338s === RUN TestNew/Success 338s === PAUSE TestNew/Success 338s === RUN TestNew/Error_when_WslPath_returns_error 338s === PAUSE TestNew/Error_when_WslPath_returns_error 338s === CONT TestServeAndQuit 338s === RUN TestServeAndQuit/Success_with_graceful_quit 338s === PAUSE TestServeAndQuit/Success_with_graceful_quit 338s === RUN TestServeAndQuit/Success_with_forceful_quit 338s === PAUSE TestServeAndQuit/Success_with_forceful_quit 338s === RUN TestServeAndQuit/Success_with_double_quit 338s === PAUSE TestServeAndQuit/Success_with_double_quit 338s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 338s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 338s === CONT TestServeAndQuit/Success_with_graceful_quit 338s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 338s === CONT TestServeAndQuit/Success_with_double_quit 338s === CONT TestServe 338s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 338s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 338s === RUN TestServe/Success 338s === PAUSE TestServe/Success 338s === RUN TestServe/Success_with_systemd_notifier_returning_true 338s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 338s === RUN TestServe/No_connection_because_the_port_file_is_empty 338s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 338s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 338s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 338s === RUN TestServe/No_connection_because_there_is_no_server 338s === PAUSE TestServe/No_connection_because_there_is_no_server 338s === RUN TestServe/No_connection_because_there_are_no_certificates 338s === PAUSE TestServe/No_connection_because_there_are_no_certificates 338s === RUN TestServe/Error_because_the_notifier_returns_an_error 338s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 338s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 338s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 338s === RUN TestServe/No_connection_because_the_port_file_has_port_0 338s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 338s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 338s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 338s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 338s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 338s === RUN TestServe/Error_because_the_context_is_pre-cancelled 338s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 338s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 338s === CONT TestNew/Error_when_WslPath_returns_error 338s === CONT TestReconnection 338s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 338s === CONT TestServeAndQuit/Success_with_forceful_quit 338s === CONT TestNew/Success 338s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 338s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 338s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 338s === RUN TestReconnection/Success_connecting_after_failing_to_connect 338s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 338s === CONT TestServe/Error_because_the_notifier_returns_an_error 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43505" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46201" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41341" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39687" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44145" 338s time="2024-06-28T12:06:09Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37771" 338s time="2024-06-28T12:06:10Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:26Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:27Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:27Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:27Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s === CONT TestServe/No_connection_because_the_port_file_is_empty 338s time="2024-06-28T12:06:28Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39487" 338s time="2024-06-28T12:06:28Z" level=debug msg="Building new daemon" 338s === CONT TestServe/Success_with_systemd_notifier_returning_true 338s --- PASS: TestNew (0.00s) 338s --- PASS: TestNew/Success (18.26s) 338s --- PASS: TestNew/Error_when_WslPath_returns_error (18.61s) 338s time="2024-06-28T12:06:28Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35325" 338s time="2024-06-28T12:06:28Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:28Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:28Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:28Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:28Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 338s time="2024-06-28T12:06:28Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:28Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:28Z" level=debug msg="All connections have now ended." 338s === CONT TestServe/Success 338s time="2024-06-28T12:06:29Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:29Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:29Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:29Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:29Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:06:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38951" 338s time="2024-06-28T12:06:29Z" level=debug msg="Building new daemon" 338s === CONT TestServe/No_connection_because_there_are_no_certificates 338s time="2024-06-28T12:06:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38309" 338s time="2024-06-28T12:06:29Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:30Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:30Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:30Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:32Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:32Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:32Z" level=debug msg="All connections have now ended." 338s === CONT TestServe/No_connection_because_there_is_no_server 338s time="2024-06-28T12:06:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42125" 338s time="2024-06-28T12:06:32Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:33Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44145" 338s time="2024-06-28T12:06:33Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:33Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:33Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:33Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:35Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39687" 338s time="2024-06-28T12:06:35Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:35Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:35Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:35Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:37Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46201" 338s time="2024-06-28T12:06:37Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:37Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:37Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:37Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:41Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:41Z" 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" 338s time="2024-06-28T12:06:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:41Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:41Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:41Z" level=info msg="Stopping active requests." 338s time="2024-06-28T12:06:41Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:41Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:06:41Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:41Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:41Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:41Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:41Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:41Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:41Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:06:41Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:41Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 338s time="2024-06-28T12:06:42Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:42Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:42Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:42Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s === CONT TestServe/Error_because_the_context_is_pre-cancelled 338s time="2024-06-28T12:06:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43151" 338s time="2024-06-28T12:06:42Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:42Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:42Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:42Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37089" 338s time="2024-06-28T12:06:42Z" level=debug msg="Building new daemon" 338s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 338s time="2024-06-28T12:06:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35539" 338s time="2024-06-28T12:06:42Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:43Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:43Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:43Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:43Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:43Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:43Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:43Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:43Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:43Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:43Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:06:43Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:43Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:43Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:43Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:43Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:43Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:43Z" level=debug msg="All connections have now ended." 338s === CONT TestServe/No_connection_because_the_port_file_has_port_0 338s --- PASS: TestServeAndQuit (0.00s) 338s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (19.62s) 338s --- PASS: TestServeAndQuit/Success_with_forceful_quit (32.27s) 338s --- PASS: TestServeAndQuit/Success_with_graceful_quit (32.46s) 338s --- PASS: TestServeAndQuit/Success_with_double_quit (34.52s) 338s time="2024-06-28T12:06:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41619" 338s time="2024-06-28T12:06:44Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:45Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:47Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35325" 338s time="2024-06-28T12:06:47Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:47Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:47Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:47Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:48Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38951" 338s time="2024-06-28T12:06:48Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:48Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:48Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:48Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38309" 338s time="2024-06-28T12:06:48Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates3521706727/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 338s time="2024-06-28T12:06:48Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:49Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:49Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:49Z" level=debug msg="All connections have now ended." 338s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 338s time="2024-06-28T12:06:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35599" 338s time="2024-06-28T12:06:49Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:50Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42125" 338s time="2024-06-28T12:06:50Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:50Z" level=warning msg="Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:42125: connect: connection refused\"" 338s time="2024-06-28T12:06:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:51Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:51Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:51Z" level=debug msg="All connections have now ended." 338s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 338s time="2024-06-28T12:06:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35087" 338s time="2024-06-28T12:06:51Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:51Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:51Z" 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" 338s time="2024-06-28T12:06:51Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:52Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:52Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:52Z" level=debug msg="All connections have now ended." 338s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 338s time="2024-06-28T12:06:52Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:52Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:52Z" 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" 338s time="2024-06-28T12:06:52Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:06:53Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:53Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:53Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:53Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:53Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:53Z" 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" 338s time="2024-06-28T12:06:53Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:53Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:53Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:53Z" level=debug msg="All connections have now ended." 338s === CONT TestReconnection/Success_connecting_after_failing_to_connect 338s time="2024-06-28T12:06:53Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:54Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:54Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:54Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:54Z" 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" 338s time="2024-06-28T12:06:54Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:06:54Z" level=debug msg="All connections have now ended." 338s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 338s time="2024-06-28T12:06:54Z" level=debug msg="Building new daemon" 338s time="2024-06-28T12:06:55Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:55Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:55Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:55Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:55Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:55Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:55Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:55Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:06:55Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:55Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:55Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_the_port_file_does_not_exist3099241221/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3099241221/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 338s time="2024-06-28T12:06:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:55Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35539" 338s time="2024-06-28T12:06:55Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file575331233/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 338s time="2024-06-28T12:06:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:56Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:56Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:portyMcPortface\": could not parse port as an integer: strconv.Atoi: parsing \"portyMcPortface\": invalid syntax" 338s time="2024-06-28T12:06:56Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:56Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:56Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:56Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:56Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:56Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:56Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:56Z" 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_exist3099241221/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3099241221/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 338s time="2024-06-28T12:06:56Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:06:56Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:06:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46573" 338s time="2024-06-28T12:06:57Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:57Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:57Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:57Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:57Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:57Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:57Z" 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_connect2266706315/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect2266706315/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 338s time="2024-06-28T12:06:57Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:57Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:57Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:57Z" 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" 338s time="2024-06-28T12:06:57Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:06:57Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:06:57Z" level=info msg="Waiting for active requests to close." 338s time="2024-06-28T12:06:57Z" level=debug msg="All connections have now ended." 338s --- PASS: TestServe (0.00s) 338s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (19.46s) 338s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (22.79s) 338s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (14.61s) 338s --- PASS: TestServe/No_connection_because_there_are_no_certificates (19.90s) 338s --- PASS: TestServe/No_connection_because_there_is_no_server (18.67s) 338s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (10.25s) 338s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (11.82s) 338s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (10.54s) 338s --- PASS: TestServe/Success (25.85s) 338s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (26.81s) 338s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (13.99s) 338s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (7.53s) 338s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (6.35s) 338s time="2024-06-28T12:06:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37079" 338s time="2024-06-28T12:06:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43163" 338s time="2024-06-28T12:06:57Z" level=debug msg="Ready state sent to systemd" 338s time="2024-06-28T12:06:57Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:57Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:58Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46573" 338s time="2024-06-28T12:06:58Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:58Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:58Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:58Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:58Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:06:58Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:06:58Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43163" 338s time="2024-06-28T12:06:58Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:58Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:58Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:58Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:59Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:06:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:59Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:59Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37079" 338s time="2024-06-28T12:06:59Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:06:59Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:06:59Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:06:59Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:06:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:59Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:06:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:06:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:06:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45705" 338s time="2024-06-28T12:07:00Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:07:00Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:07:00Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:07:00Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:07:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:07:00Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:00Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:00Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:00Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:07:00Z" level=info msg="Stopping active requests." 338s time="2024-06-28T12:07:00Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:07:00Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:07:00Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:07:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:07:00Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:07:01Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45705" 338s time="2024-06-28T12:07:01Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:07:01Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:07:01Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:07:01Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:07:02Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:07:02Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:07:02Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:07:02Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:07:03Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:03Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:03Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:07:03Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:07:03Z" level=info msg="Stopping active requests." 338s time="2024-06-28T12:07:03Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:07:03Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:07:03Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:07:03Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:07:03Z" level=debug msg="All connections have now ended." 338s time="2024-06-28T12:08:05Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:05Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:05Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:05Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:08:05Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:08:05Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:08:05Z" 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_dropped3561101354/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped3561101354/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 338s time="2024-06-28T12:08:05Z" level=info msg="Reconnecting to Windows host in 1 seconds" 338s time="2024-06-28T12:08:05Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:08:05Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34155" 338s time="2024-06-28T12:08:06Z" level=info msg="Daemon: connecting to Windows Agent" 338s time="2024-06-28T12:08:06Z" level=debug msg="Updated systemd status to \"Connecting\"" 338s time="2024-06-28T12:08:07Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34155" 338s time="2024-06-28T12:08:07Z" level=info msg="Daemon: completed connection to Windows Agent" 338s time="2024-06-28T12:08:07Z" level=debug msg="Updated systemd status to \"Connected\"" 338s time="2024-06-28T12:08:07Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 338s time="2024-06-28T12:08:07Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 338s time="2024-06-28T12:08:08Z" level=debug msg="Server: sent preface messages to all streams" 338s time="2024-06-28T12:08:08Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:08:08Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:08:08Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:08:08Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:08Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:08Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:08:08Z" level=info msg="Stopping daemon requested." 338s time="2024-06-28T12:08:08Z" level=info msg="Stopping active requests." 338s time="2024-06-28T12:08:08Z" level=warning msg="Daemon: disconnected from Windows host" 338s time="2024-06-28T12:08:08Z" level=info msg="Reconnecting to Windows host in 2 seconds" 338s time="2024-06-28T12:08:08Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 338s time="2024-06-28T12:08:08Z" level=debug msg="Updated systemd status to \"Stopped\"" 338s time="2024-06-28T12:08:08Z" level=debug msg="All connections have now ended." 338s --- PASS: TestReconnection (0.00s) 338s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (7.03s) 338s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (10.57s) 338s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (73.70s) 338s PASS 338s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 118.823s 338s === RUN TestConnect 338s === PAUSE TestConnect 338s === RUN TestSendAndRecv 338s --- PASS: TestSendAndRecv (0.42s) 338s === RUN TestServe 338s === PAUSE TestServe 338s === RUN TestStop 338s === PAUSE TestStop 338s === RUN TestWithProMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithProMock (0.00s) 338s === RUN TestWithWslPathMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslPathMock (0.00s) 338s === RUN TestWithWslInfoMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslInfoMock (0.00s) 338s === RUN TestWithCmdExeMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithCmdExeMock (0.00s) 338s === CONT TestConnect 338s === RUN TestConnect/Success 338s === PAUSE TestConnect/Success 338s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 338s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 338s === CONT TestConnect/Success 338s === CONT TestServe 338s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 338s === CONT TestStop 338s time="2024-06-28T12:06:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40021" 338s time="2024-06-28T12:06:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45509" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: Connected ready" 338s time="2024-06-28T12:06:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 338s --- PASS: TestConnect (0.00s) 338s --- PASS: TestConnect/Success (0.31s) 338s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.00s) 338s time="2024-06-28T12:06:31Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:31Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:31Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s --- PASS: TestStop (21.37s) 338s time="2024-06-28T12:06:42Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:42Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 338s time="2024-06-28T12:06:42Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 338s --- PASS: TestServe (32.23s) 338s PASS 338s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 32.692s 338s === RUN TestInfo 338s === PAUSE TestInfo 338s === RUN TestWslDistroName 338s === PAUSE TestWslDistroName 338s === RUN TestUserProfileDir 338s === PAUSE TestUserProfileDir 338s === RUN TestProStatus 338s === PAUSE TestProStatus 338s === RUN TestProAttach 338s === PAUSE TestProAttach 338s === RUN TestProDetach 338s === PAUSE TestProDetach 338s === RUN TestLandscapeEnable 338s === PAUSE TestLandscapeEnable 338s === RUN TestWindowsHostAddress 338s === PAUSE TestWindowsHostAddress 338s === RUN TestLandscapeDisable 338s === PAUSE TestLandscapeDisable 338s === RUN TestRealBackend 338s === PAUSE TestRealBackend 338s === RUN TestWithProMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithProMock (0.00s) 338s === RUN TestWithLandscapeConfigMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithLandscapeConfigMock (0.00s) 338s === RUN TestWithWslPathMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslPathMock (0.00s) 338s === RUN TestWithWslInfoMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithWslInfoMock (0.00s) 338s === RUN TestWithCmdExeMock 338s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 338s --- SKIP: TestWithCmdExeMock (0.00s) 338s === CONT TestInfo 338s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 338s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 338s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 338s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 338s === RUN TestInfo/Success 338s === PAUSE TestInfo/Success 338s === RUN TestInfo/Error_when_WslDistroName_fails 338s === PAUSE TestInfo/Error_when_WslDistroName_fails 338s === RUN TestInfo/Error_when_pro_status_command_fails 338s === PAUSE TestInfo/Error_when_pro_status_command_fails 338s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 338s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 338s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 338s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 338s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 338s === CONT TestUserProfileDir 338s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 338s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 338s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 338s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 338s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 338s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 338s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 338s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 338s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 338s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 338s === RUN TestUserProfileDir/Error_on_cmd.exe_error 338s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 338s === CONT TestLandscapeEnable 338s === RUN TestLandscapeEnable/Success 338s === PAUSE TestLandscapeEnable/Success 338s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 338s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 338s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 338s === CONT TestRealBackend 338s === CONT TestLandscapeDisable 338s === RUN TestLandscapeDisable/Success 338s === PAUSE TestLandscapeDisable/Success 338s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 338s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 338s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 338s === CONT TestWindowsHostAddress 338s === RUN TestWindowsHostAddress/Success_without_NAT 338s === PAUSE TestWindowsHostAddress/Success_without_NAT 338s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 338s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 338s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 338s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 338s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 338s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 338s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 338s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 338s === CONT TestInfo/Error_when_pro_status_command_fails 338s === CONT TestWslDistroName 338s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 338s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 338s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 338s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 338s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 338s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 338s === RUN TestWslDistroName/Success_using_wslpath 338s === PAUSE TestWslDistroName/Success_using_wslpath 338s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 338s === RUN TestUserProfileDir/Error_on_wslpath_error 338s === PAUSE TestUserProfileDir/Error_on_wslpath_error 338s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 338s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 338s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 338s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 338s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 338s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 338s === CONT TestInfo/Success 338s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 338s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 338s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 338s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 338s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 338s === RUN TestLandscapeEnable/Success_overriding_computer_title 338s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 338s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 338s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 338s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 338s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 338s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 338s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 338s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 338s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 338s === CONT TestInfo/Error_when_WslDistroName_fails 338s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 338s --- PASS: TestRealBackend (0.06s) 338s === CONT TestProAttach 338s === RUN TestProAttach/success 338s === PAUSE TestProAttach/success 338s === RUN TestProAttach/error_on_'pro_attach'_error 338s === PAUSE TestProAttach/error_on_'pro_attach'_error 338s === CONT TestProDetach 338s === RUN TestProDetach/success_on_unattached_distro 338s === PAUSE TestProDetach/success_on_unattached_distro 338s === RUN TestProDetach/success_on_attached_distro 338s === PAUSE TestProDetach/success_on_attached_distro 338s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 338s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 338s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 338s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 338s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 338s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 338s === CONT TestProStatus 338s === RUN TestProStatus/success_on_unattached_distro 338s === PAUSE TestProStatus/success_on_unattached_distro 338s === RUN TestProStatus/success_on_attached_distro 338s === PAUSE TestProStatus/success_on_attached_distro 338s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 338s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 338s === RUN TestProStatus/error_on_'pro_attach'_error 338s === PAUSE TestProStatus/error_on_'pro_attach'_error 338s === CONT TestLandscapeDisable/Success 338s === CONT TestWindowsHostAddress/Success_without_NAT 338s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 338s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 338s --- PASS: TestInfo (0.00s) 338s --- PASS: TestInfo/Error_when_pro_status_command_fails (8.87s) 338s --- PASS: TestInfo/Success (8.90s) 338s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (9.29s) 338s --- PASS: TestInfo/Error_when_WslDistroName_fails (9.77s) 338s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (10.09s) 338s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (10.25s) 338s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (13.81s) 338s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 338s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 338s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 338s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 338s === CONT TestWslDistroName/Success_using_wslpath 338s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 338s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 338s === CONT TestLandscapeEnable/Success 338s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 338s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 338s === NAME TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 338s system_test.go:205: Removing default proc/mounts 338s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 338s === CONT TestUserProfileDir/Error_on_wslpath_error 338s === CONT TestUserProfileDir/Error_on_cmd.exe_error 338s --- PASS: TestWindowsHostAddress (0.00s) 338s --- PASS: TestWindowsHostAddress/Success_without_NAT (5.90s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (6.16s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (6.32s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (6.99s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (6.89s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (8.05s) 338s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (6.55s) 338s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (7.63s) 338s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (5.54s) 338s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (7.20s) 338s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 338s system_test.go:205: Removing default proc/mounts 338s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 338s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 338s --- PASS: TestWslDistroName (0.00s) 338s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (7.28s) 338s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.01s) 338s --- PASS: TestWslDistroName/Success_using_wslpath (7.67s) 338s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (8.27s) 338s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 338s === NAME TestLandscapeEnable/Success 338s system_test.go:456: testdata/TestLandscapeEnable/golden/success 338s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 338s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 338s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 338s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 338s === CONT TestLandscapeEnable/Success_overriding_computer_title 338s time="2024-06-28T12:06:40Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 338s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 338s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 338s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 338s time="2024-06-28T12:06:45Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 338s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 338s time="2024-06-28T12:06:45Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 338s === NAME TestLandscapeEnable/Success_overriding_computer_title 338s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 338s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 338s === CONT TestProAttach/success 338s === CONT TestProDetach/success_on_unattached_distro 338s --- PASS: TestUserProfileDir (0.00s) 338s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.28s) 338s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.01s) 338s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 338s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (6.25s) 338s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (14.49s) 338s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (13.81s) 338s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (13.68s) 338s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (11.41s) 338s --- PASS: TestUserProfileDir/Error_on_wslpath_error (14.93s) 338s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (11.26s) 338s === CONT TestProAttach/error_on_'pro_attach'_error 338s === CONT TestProStatus/success_on_unattached_distro 338s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 338s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 338s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 338s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 338s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 338s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 338s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 338s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 338s --- PASS: TestLandscapeEnable (0.01s) 338s --- PASS: TestLandscapeEnable/Success (7.70s) 338s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.00s) 338s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.01s) 338s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (6.14s) 338s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.06s) 338s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (4.92s) 338s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (5.54s) 338s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (4.94s) 338s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (11.42s) 338s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (5.27s) 338s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 338s === CONT TestProDetach/success_on_attached_distro 338s --- PASS: TestProAttach (0.00s) 338s --- PASS: TestProAttach/success (5.24s) 338s --- PASS: TestProAttach/error_on_'pro_attach'_error (5.19s) 338s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 338s --- PASS: TestLandscapeDisable (0.00s) 338s --- PASS: TestLandscapeDisable/Success (11.75s) 338s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (6.30s) 338s === CONT TestProStatus/error_on_'pro_attach'_error 338s === CONT TestProStatus/success_on_attached_distro 338s --- PASS: TestProDetach (0.00s) 338s --- PASS: TestProDetach/success_on_unattached_distro (5.42s) 338s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (4.12s) 338s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (4.32s) 338s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (4.18s) 338s --- PASS: TestProDetach/success_on_attached_distro (4.07s) 338s --- PASS: TestProStatus (0.00s) 338s --- PASS: TestProStatus/success_on_unattached_distro (4.42s) 338s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (3.73s) 338s --- PASS: TestProStatus/success_on_attached_distro (3.66s) 338s --- PASS: TestProStatus/error_on_'pro_attach'_error (3.86s) 338s PASS 338s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 45.845s 339s autopkgtest [12:08:09]: test command1: -----------------------] 342s autopkgtest [12:08:12]: test command1: - - - - - - - - - - results - - - - - - - - - - 342s command1 PASS 346s autopkgtest [12:08:16]: @@@@@@@@@@@@@@@@@@@@ summary 346s command1 PASS