0s autopkgtest [05:10:35]: starting date and time: 2024-07-31 05:10:35+0000 0s autopkgtest [05:10:35]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [05:10:35]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.30otgy_j/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/33.2~24.04' -- lxd -r lxd-armhf-10.145.243.177 lxd-armhf-10.145.243.177:autopkgtest/ubuntu/noble/armhf 98s autopkgtest [05:12:13]: testbed dpkg architecture: armhf 101s autopkgtest [05:12:16]: testbed apt version: 2.7.14build2 101s autopkgtest [05:12:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 113s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 117s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [10.2 kB] 117s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [8240 B] 118s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [34.7 kB] 119s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [71.6 kB] 119s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2212 B] 119s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1176 B] 119s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 119s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [47.3 kB] 119s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [2152 B] 119s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 123s Fetched 443 kB in 7s (65.6 kB/s) 124s Reading package lists... 154s tee: /proc/self/fd/2: Permission denied 186s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 186s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 186s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 186s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 201s Reading package lists... 202s Reading package lists... 202s Building dependency tree... 202s Reading state information... 203s Calculating upgrade... 203s The following packages will be upgraded: 203s ubuntu-pro-client ubuntu-pro-client-l10n 203s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 203s Need to get 252 kB of archives. 203s After this operation, 13.3 kB of additional disk space will be used. 203s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 33.2~24.04 [19.3 kB] 204s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 33.2~24.04 [233 kB] 208s Fetched 252 kB in 0s (532 kB/s) 210s (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 ... 57906 files and directories currently installed.) 210s Preparing to unpack .../ubuntu-pro-client-l10n_33.2~24.04_armhf.deb ... 210s Unpacking ubuntu-pro-client-l10n (33.2~24.04) over (32.3.1~24.04) ... 212s Preparing to unpack .../ubuntu-pro-client_33.2~24.04_armhf.deb ... 212s Unpacking ubuntu-pro-client (33.2~24.04) over (32.3.1~24.04) ... 214s Setting up ubuntu-pro-client (33.2~24.04) ... 214s Installing new version of config file /etc/apparmor.d/ubuntu_pro_apt_news ... 214s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 214s 215s apparmor_parser: Unable to replace "apt_methods". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 215s 215s apparmor_parser: Unable to replace "ubuntu_pro_esm_cache". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 215s 222s Setting up ubuntu-pro-client-l10n (33.2~24.04) ... 222s Processing triggers for man-db (2.12.0-4build2) ... 224s Reading package lists... 224s Building dependency tree... 224s Reading state information... 225s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 228s autopkgtest [05:14:23]: rebooting testbed after setup commands that affected boot 303s autopkgtest [05:15:38]: testbed running kernel: Linux 6.5.0-45-generic #45~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Mon Jul 15 20:13:54 UTC 2 361s autopkgtest [05:16:36]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 482s Get:1 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (dsc) [1420 B] 482s Get:2 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (tar) [1762 kB] 498s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 498s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 498s gpgv: Can't check signature: No public key 498s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 522s autopkgtest [05:19:17]: testing package wsl-pro-service version 0.1.4 532s autopkgtest [05:19:27]: build not needed 538s autopkgtest [05:19:33]: test command1: preparing testbed 564s Reading package lists... 564s Building dependency tree... 566s Reading state information... 567s Starting pkgProblemResolver with broken count: 0 567s Starting 2 pkgProblemResolver with broken count: 0 567s Done 569s The following additional packages will be installed: 569s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 569s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 569s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 569s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 569s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 569s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 569s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 569s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 569s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 569s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 569s rpcsvc-proto 569s Suggested packages: 569s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 569s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 569s gcc-doc gdb-arm-linux-gnueabihf gettext-doc libasprintf-dev libgettextpo-dev 569s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 569s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 569s Recommended packages: 569s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 569s libltdl-dev libmail-sendmail-perl 577s The following NEW packages will be installed: 577s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 577s cpp cpp-13 cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper 577s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 577s g++-13 g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 577s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 577s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 577s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 577s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 577s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 577s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 577s rpcsvc-proto 583s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 583s Need to get 95.3 MB/95.3 MB of archives. 583s After this operation, 377 MB of additional disk space will be used. 583s Get:1 /tmp/autopkgtest.Wtvhwo/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [760 B] 588s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf m4 armhf 1.4.19-4build1 [235 kB] 589s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf autoconf all 2.71-3 [339 kB] 589s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf autotools-dev all 20220109.1 [44.9 kB] 589s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf automake all 1:1.16.5-1.3ubuntu1 [558 kB] 589s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf autopoint all 0.21-14ubuntu2 [422 kB] 589s Get:7 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc-dev-bin armhf 2.39-0ubuntu8.2 [19.1 kB] 589s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main armhf linux-libc-dev armhf 6.8.0-39.39 [1604 kB] 590s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf libcrypt-dev armhf 1:4.4.36-4build1 [120 kB] 590s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf rpcsvc-proto armhf 1.4.2-0ubuntu7 [62.2 kB] 590s Get:11 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc6-dev armhf 2.39-0ubuntu8.2 [1351 kB] 590s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-base armhf 13.2.0-23ubuntu4 [48.9 kB] 590s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libisl23 armhf 0.26-3build1 [544 kB] 590s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf libmpc3 armhf 1.3.1-1build1 [46.7 kB] 590s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [8759 kB] 592s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-23ubuntu4 [1034 B] 592s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 592s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 592s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240412-0ubuntu1 [39.0 kB] 592s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240412-0ubuntu1 [125 kB] 592s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240412-0ubuntu1 [7816 B] 592s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240412-0ubuntu1 [2942 kB] 592s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240412-0ubuntu1 [1154 kB] 592s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-23ubuntu4 [899 kB] 592s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [16.9 MB] 595s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13 armhf 13.2.0-23ubuntu4 [453 kB] 595s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf gcc-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [1220 B] 595s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf gcc armhf 4:13.2.0-7ubuntu1 [5022 B] 595s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf libstdc++-13-dev armhf 13.2.0-23ubuntu4 [2456 kB] 595s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf g++-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [9941 kB] 596s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf g++-13 armhf 13.2.0-23ubuntu4 [14.5 kB] 596s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf g++-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [966 B] 596s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf g++ armhf 4:13.2.0-7ubuntu1 [1090 B] 596s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf build-essential armhf 12.10ubuntu1 [4928 B] 596s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 596s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf libtool all 2.4.7-7build1 [166 kB] 596s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf dh-autoreconf all 20 [16.1 kB] 596s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 596s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libsub-override-perl all 0.10-1 [10.0 kB] 596s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 596s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf dh-strip-nondeterminism all 1.13.1-1 [5362 B] 596s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf libdw1t64 armhf 0.190-1.1build4 [235 kB] 596s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5build2 [42.3 kB] 596s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1build6 [116 kB] 596s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu2 [800 kB] 596s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 596s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 596s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.14.1ubuntu5 [869 kB] 596s Get:49 http://ftpmaster.internal/ubuntu noble-updates/universe armhf dh-apport all 2.28.1-0ubuntu3 [17.2 kB] 596s Get:50 http://ftpmaster.internal/ubuntu noble-updates/main armhf golang-1.22-src all 1.22.2-2ubuntu0.1 [19.7 MB] 598s Get:51 http://ftpmaster.internal/ubuntu noble-updates/main armhf golang-1.22-go armhf 1.22.2-2ubuntu0.1 [23.7 MB] 600s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.22~2build1 [5078 B] 600s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.22~2build1 [43.9 kB] 600s Get:54 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 614s Fetched 95.3 MB in 22s (4239 kB/s) 615s Selecting previously unselected package m4. 615s (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 ... 57907 files and directories currently installed.) 615s Preparing to unpack .../00-m4_1.4.19-4build1_armhf.deb ... 615s Unpacking m4 (1.4.19-4build1) ... 616s Selecting previously unselected package autoconf. 617s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 617s Unpacking autoconf (2.71-3) ... 617s Selecting previously unselected package autotools-dev. 619s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 619s Unpacking autotools-dev (20220109.1) ... 619s Selecting previously unselected package automake. 621s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 621s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 622s Selecting previously unselected package autopoint. 624s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 624s Unpacking autopoint (0.21-14ubuntu2) ... 625s Selecting previously unselected package libc-dev-bin. 627s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu8.2_armhf.deb ... 627s Unpacking libc-dev-bin (2.39-0ubuntu8.2) ... 628s Selecting previously unselected package linux-libc-dev:armhf. 631s Preparing to unpack .../06-linux-libc-dev_6.8.0-39.39_armhf.deb ... 631s Unpacking linux-libc-dev:armhf (6.8.0-39.39) ... 633s Selecting previously unselected package libcrypt-dev:armhf. 636s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4build1_armhf.deb ... 637s Unpacking libcrypt-dev:armhf (1:4.4.36-4build1) ... 637s Selecting previously unselected package rpcsvc-proto. 640s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu7_armhf.deb ... 640s Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ... 641s Selecting previously unselected package libc6-dev:armhf. 642s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu8.2_armhf.deb ... 644s Unpacking libc6-dev:armhf (2.39-0ubuntu8.2) ... 644s Selecting previously unselected package gcc-13-base:armhf. 646s Preparing to unpack .../10-gcc-13-base_13.2.0-23ubuntu4_armhf.deb ... 646s Unpacking gcc-13-base:armhf (13.2.0-23ubuntu4) ... 646s Selecting previously unselected package libisl23:armhf. 648s Preparing to unpack .../11-libisl23_0.26-3build1_armhf.deb ... 648s Unpacking libisl23:armhf (0.26-3build1) ... 649s Selecting previously unselected package libmpc3:armhf. 651s Preparing to unpack .../12-libmpc3_1.3.1-1build1_armhf.deb ... 651s Unpacking libmpc3:armhf (1.3.1-1build1) ... 651s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 653s Preparing to unpack .../13-cpp-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 653s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 654s Selecting previously unselected package cpp-13. 657s Preparing to unpack .../14-cpp-13_13.2.0-23ubuntu4_armhf.deb ... 657s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 657s Selecting previously unselected package cpp-arm-linux-gnueabihf. 658s Preparing to unpack .../15-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 658s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 659s Selecting previously unselected package cpp. 661s Preparing to unpack .../16-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 661s Unpacking cpp (4:13.2.0-7ubuntu1) ... 662s Selecting previously unselected package libcc1-0:armhf. 663s Preparing to unpack .../17-libcc1-0_14-20240412-0ubuntu1_armhf.deb ... 663s Unpacking libcc1-0:armhf (14-20240412-0ubuntu1) ... 663s Selecting previously unselected package libgomp1:armhf. 665s Preparing to unpack .../18-libgomp1_14-20240412-0ubuntu1_armhf.deb ... 665s Unpacking libgomp1:armhf (14-20240412-0ubuntu1) ... 665s Selecting previously unselected package libatomic1:armhf. 666s Preparing to unpack .../19-libatomic1_14-20240412-0ubuntu1_armhf.deb ... 666s Unpacking libatomic1:armhf (14-20240412-0ubuntu1) ... 666s Selecting previously unselected package libasan8:armhf. 667s Preparing to unpack .../20-libasan8_14-20240412-0ubuntu1_armhf.deb ... 667s Unpacking libasan8:armhf (14-20240412-0ubuntu1) ... 667s Selecting previously unselected package libubsan1:armhf. 668s Preparing to unpack .../21-libubsan1_14-20240412-0ubuntu1_armhf.deb ... 668s Unpacking libubsan1:armhf (14-20240412-0ubuntu1) ... 668s Selecting previously unselected package libgcc-13-dev:armhf. 668s Preparing to unpack .../22-libgcc-13-dev_13.2.0-23ubuntu4_armhf.deb ... 668s Unpacking libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 668s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 668s Preparing to unpack .../23-gcc-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 668s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 669s Selecting previously unselected package gcc-13. 669s Preparing to unpack .../24-gcc-13_13.2.0-23ubuntu4_armhf.deb ... 669s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 669s Selecting previously unselected package gcc-arm-linux-gnueabihf. 669s Preparing to unpack .../25-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 669s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 669s Selecting previously unselected package gcc. 669s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 669s Unpacking gcc (4:13.2.0-7ubuntu1) ... 669s Selecting previously unselected package libstdc++-13-dev:armhf. 669s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-23ubuntu4_armhf.deb ... 669s Unpacking libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 669s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 670s Preparing to unpack .../28-g++-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 670s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 670s Selecting previously unselected package g++-13. 670s Preparing to unpack .../29-g++-13_13.2.0-23ubuntu4_armhf.deb ... 670s Unpacking g++-13 (13.2.0-23ubuntu4) ... 670s Selecting previously unselected package g++-arm-linux-gnueabihf. 671s Preparing to unpack .../30-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 671s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 672s Selecting previously unselected package g++. 674s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 674s Unpacking g++ (4:13.2.0-7ubuntu1) ... 675s Selecting previously unselected package build-essential. 677s Preparing to unpack .../32-build-essential_12.10ubuntu1_armhf.deb ... 677s Unpacking build-essential (12.10ubuntu1) ... 678s Selecting previously unselected package libdebhelper-perl. 681s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 681s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 682s Selecting previously unselected package libtool. 685s Preparing to unpack .../34-libtool_2.4.7-7build1_all.deb ... 685s Unpacking libtool (2.4.7-7build1) ... 686s Selecting previously unselected package dh-autoreconf. 689s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 689s Unpacking dh-autoreconf (20) ... 690s Selecting previously unselected package libarchive-zip-perl. 693s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 693s Unpacking libarchive-zip-perl (1.68-1) ... 694s Selecting previously unselected package libsub-override-perl. 698s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 698s Unpacking libsub-override-perl (0.10-1) ... 699s Selecting previously unselected package libfile-stripnondeterminism-perl. 703s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 703s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 703s Selecting previously unselected package dh-strip-nondeterminism. 706s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 706s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 706s Selecting previously unselected package libdw1t64:armhf. 709s Preparing to unpack .../40-libdw1t64_0.190-1.1build4_armhf.deb ... 709s Unpacking libdw1t64:armhf (0.190-1.1build4) ... 709s Selecting previously unselected package debugedit. 711s Preparing to unpack .../41-debugedit_1%3a5.0-5build2_armhf.deb ... 711s Unpacking debugedit (1:5.0-5build2) ... 711s Selecting previously unselected package dwz. 713s Preparing to unpack .../42-dwz_0.15-1build6_armhf.deb ... 713s Unpacking dwz (0.15-1build6) ... 713s Selecting previously unselected package gettext. 715s Preparing to unpack .../43-gettext_0.21-14ubuntu2_armhf.deb ... 715s Unpacking gettext (0.21-14ubuntu2) ... 716s Selecting previously unselected package intltool-debian. 718s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 718s Unpacking intltool-debian (0.35.0+20060710.6) ... 719s Selecting previously unselected package po-debconf. 722s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 722s Unpacking po-debconf (1.0.21+nmu1) ... 723s Selecting previously unselected package debhelper. 726s Preparing to unpack .../46-debhelper_13.14.1ubuntu5_all.deb ... 726s Unpacking debhelper (13.14.1ubuntu5) ... 727s Selecting previously unselected package dh-apport. 730s Preparing to unpack .../47-dh-apport_2.28.1-0ubuntu3_all.deb ... 730s Unpacking dh-apport (2.28.1-0ubuntu3) ... 731s Selecting previously unselected package golang-1.22-src. 733s Preparing to unpack .../48-golang-1.22-src_1.22.2-2ubuntu0.1_all.deb ... 733s Unpacking golang-1.22-src (1.22.2-2ubuntu0.1) ... 742s Selecting previously unselected package golang-1.22-go. 747s Preparing to unpack .../49-golang-1.22-go_1.22.2-2ubuntu0.1_armhf.deb ... 747s Unpacking golang-1.22-go (1.22.2-2ubuntu0.1) ... 750s Selecting previously unselected package golang-src. 756s Preparing to unpack .../50-golang-src_2%3a1.22~2build1_all.deb ... 756s Unpacking golang-src (2:1.22~2build1) ... 757s Selecting previously unselected package golang-go:armhf. 761s Preparing to unpack .../51-golang-go_2%3a1.22~2build1_armhf.deb ... 761s Unpacking golang-go:armhf (2:1.22~2build1) ... 762s Selecting previously unselected package dh-golang. 765s Preparing to unpack .../52-dh-golang_1.62_all.deb ... 765s Unpacking dh-golang (1.62) ... 765s Selecting previously unselected package autopkgtest-satdep. 766s Preparing to unpack .../53-1-autopkgtest-satdep.deb ... 766s Unpacking autopkgtest-satdep (0) ... 767s Setting up libarchive-zip-perl (1.68-1) ... 767s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 767s Setting up linux-libc-dev:armhf (6.8.0-39.39) ... 767s Setting up m4 (1.4.19-4build1) ... 767s Setting up libgomp1:armhf (14-20240412-0ubuntu1) ... 767s Setting up dh-apport (2.28.1-0ubuntu3) ... 767s Setting up libdw1t64:armhf (0.190-1.1build4) ... 767s Setting up autotools-dev (20220109.1) ... 767s Setting up rpcsvc-proto (1.4.2-0ubuntu7) ... 767s Setting up gcc-13-base:armhf (13.2.0-23ubuntu4) ... 767s Setting up golang-1.22-src (1.22.2-2ubuntu0.1) ... 767s Setting up libmpc3:armhf (1.3.1-1build1) ... 767s Setting up libatomic1:armhf (14-20240412-0ubuntu1) ... 767s Setting up autopoint (0.21-14ubuntu2) ... 767s Setting up autoconf (2.71-3) ... 767s Setting up libubsan1:armhf (14-20240412-0ubuntu1) ... 767s Setting up dwz (0.15-1build6) ... 767s Setting up libcrypt-dev:armhf (1:4.4.36-4build1) ... 767s Setting up libasan8:armhf (14-20240412-0ubuntu1) ... 767s Setting up debugedit (1:5.0-5build2) ... 767s Setting up libsub-override-perl (0.10-1) ... 767s Setting up libisl23:armhf (0.26-3build1) ... 767s Setting up libc-dev-bin (2.39-0ubuntu8.2) ... 767s Setting up golang-src (2:1.22~2build1) ... 767s Setting up libcc1-0:armhf (14-20240412-0ubuntu1) ... 767s Setting up automake (1:1.16.5-1.3ubuntu1) ... 767s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 767s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 767s Setting up gettext (0.21-14ubuntu2) ... 767s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 767s Setting up golang-1.22-go (1.22.2-2ubuntu0.1) ... 767s Setting up intltool-debian (0.35.0+20060710.6) ... 767s Setting up dh-strip-nondeterminism (1.13.1-1) ... 767s Setting up libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 767s Setting up libc6-dev:armhf (2.39-0ubuntu8.2) ... 767s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 767s Setting up libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 767s Setting up cpp-13 (13.2.0-23ubuntu4) ... 767s Setting up golang-go:armhf (2:1.22~2build1) ... 767s Setting up po-debconf (1.0.21+nmu1) ... 767s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 767s Setting up gcc-13 (13.2.0-23ubuntu4) ... 767s Setting up cpp (4:13.2.0-7ubuntu1) ... 767s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 767s Setting up g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 767s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 767s Setting up g++-13 (13.2.0-23ubuntu4) ... 767s Setting up libtool (2.4.7-7build1) ... 767s Setting up gcc (4:13.2.0-7ubuntu1) ... 767s Setting up dh-autoreconf (20) ... 767s Setting up g++ (4:13.2.0-7ubuntu1) ... 767s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 767s Setting up build-essential (12.10ubuntu1) ... 767s Setting up debhelper (13.14.1ubuntu5) ... 767s Setting up dh-golang (1.62) ... 767s Setting up autopkgtest-satdep (0) ... 767s Processing triggers for man-db (2.12.0-4build2) ... 771s Processing triggers for install-info (7.1-3build2) ... 771s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 794s (Reading database ... 76047 files and directories currently installed.) 794s Removing autopkgtest-satdep (0) ... 804s autopkgtest [05:23:59]: test command1: ./debian/tests/test 804s autopkgtest [05:23:59]: test command1: [----------------------- 962s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 962s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 964s === RUN TestRunSignal 964s === RUN TestRunSignal/Send_SIGINT_exits 964s INFO Starting WSL Pro Service version Dev 964s === RUN TestRunSignal/Send_SIGTERM_exits 964s INFO Starting WSL Pro Service version Dev 964s --- PASS: TestRunSignal (0.21s) 964s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 964s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 964s === RUN TestRun 964s === PAUSE TestRun 964s === CONT TestRun 964s === RUN TestRun/Run_and_exit_successfully 964s === PAUSE TestRun/Run_and_exit_successfully 964s === RUN TestRun/Run_and_return_error 964s === PAUSE TestRun/Run_and_return_error 964s === RUN TestRun/Run_and_return_usage_error 964s === PAUSE TestRun/Run_and_return_usage_error 964s === RUN TestRun/Run_and_usage_error_only_does_not_fail 964s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 964s === CONT TestRun/Run_and_exit_successfully 964s INFO Starting WSL Pro Service version Dev 964s === CONT TestRun/Run_and_usage_error_only_does_not_fail 964s INFO Starting WSL Pro Service version Dev 964s === CONT TestRun/Run_and_return_error 964s INFO Starting WSL Pro Service version Dev 964s === CONT TestRun/Run_and_return_usage_error 964s INFO Starting WSL Pro Service version Dev 964s ERROR context.Background Error requested 964s ERROR context.Background Error requested 964s --- PASS: TestRun (0.00s) 964s --- PASS: TestRun/Run_and_return_error (0.10s) 964s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 964s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 964s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 964s PASS 964s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 2.592s 990s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 1057s === RUN TestHelp 1057s --- PASS: TestHelp (0.17s) 1057s === RUN TestCompletion 1057s --- PASS: TestCompletion (0.03s) 1057s === RUN TestVersion 1057s --- PASS: TestVersion (0.00s) 1057s === RUN TestNoUsageError 1057s --- PASS: TestNoUsageError (0.08s) 1057s === RUN TestUsageError 1057s === PAUSE TestUsageError 1057s === RUN TestCanQuitWhenExecute 1057s === PAUSE TestCanQuitWhenExecute 1057s === RUN TestCanQuitTwice 1057s === PAUSE TestCanQuitTwice 1057s === RUN TestAppCanQuitWithoutExecute 1057s === PAUSE TestAppCanQuitWithoutExecute 1057s === RUN TestAppRunFailsOnComponentsCreationAndQuit 1057s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 1057s === RUN TestAppGetRootCmd 1057s === PAUSE TestAppGetRootCmd 1057s === RUN TestWithProMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithProMock (0.00s) 1057s === RUN TestWithWslPathMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithWslPathMock (0.00s) 1057s === RUN TestWithWslInfoMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithWslInfoMock (0.00s) 1057s === RUN TestWithCmdExeMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithCmdExeMock (0.00s) 1057s === CONT TestUsageError 1057s === CONT TestAppCanQuitWithoutExecute 1057s --- PASS: TestUsageError (0.00s) 1057s === CONT TestCanQuitTwice 1057s === NAME TestAppCanQuitWithoutExecute 1057s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 1057s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 1057s === CONT TestAppGetRootCmd 1057s --- PASS: TestAppGetRootCmd (0.07s) 1057s === CONT TestAppRunFailsOnComponentsCreationAndQuit 1057s === CONT TestCanQuitWhenExecute 1057s time="2024-07-31T05:26:45Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1057s time="2024-07-31T05:26:45Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:75 New() Building new daemon" 1057s time="2024-07-31T05:26:45Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44379" 1057s time="2024-07-31T05:26:45Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1057s time="2024-07-31T05:26:45Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:75 New() Building new daemon" 1057s time="2024-07-31T05:27:46Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1057s time="2024-07-31T05:27:46Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1057s time="2024-07-31T05:27:47Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:27:47Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:27:50Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1057s time="2024-07-31T05:27:50Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:27:50Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:27:53Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:46217" 1057s time="2024-07-31T05:27:53Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1057s time="2024-07-31T05:27:53Z" level=warning msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/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:46217: connect: connection refused\"" 1057s time="2024-07-31T05:27:53Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1057s time="2024-07-31T05:27:53Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s time="2024-07-31T05:27:53Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:27:53Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:27:53Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s --- PASS: TestCanQuitWhenExecute (69.20s) 1057s time="2024-07-31T05:27:54Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:27:54Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:27:54Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (70.51s) 1057s time="2024-07-31T05:27:59Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:41859" 1057s time="2024-07-31T05:27:59Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1057s time="2024-07-31T05:27:59Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 1057s time="2024-07-31T05:27:59Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 1057s time="2024-07-31T05:28:12Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/streams/server.go:141 (*Server).Serve() Server: sent preface messages to all streams" 1057s time="2024-07-31T05:28:12Z" level=warning msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:184 (*Daemon).Serve.func1() Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: context canceled" 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1057s time="2024-07-31T05:28:12Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:28:12Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 1057s time="2024-07-31T05:28:12Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1057s time="2024-07-31T05:28:12Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 1057s time="2024-07-31T05:28:12Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1057s time="2024-07-31T05:28:12Z" level=info msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1057s time="2024-07-31T05:28:12Z" level=debug msg="/tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1057s --- PASS: TestCanQuitTwice (88.86s) 1057s PASS 1057s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 95.117s 1057s === RUN TestApplyProToken 1057s === PAUSE TestApplyProToken 1057s === RUN TestApplyLandscapeConfig 1057s === PAUSE TestApplyLandscapeConfig 1057s === RUN TestWithProMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithProMock (0.00s) 1057s === RUN TestWithLandscapeConfigMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1057s === RUN TestWithWslPathMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithWslPathMock (0.00s) 1057s === RUN TestWithWslInfoMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithWslInfoMock (0.00s) 1057s === RUN TestWithCmdExeMock 1057s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1057s --- SKIP: TestWithCmdExeMock (0.00s) 1057s === CONT TestApplyProToken 1057s === RUN TestApplyProToken/Success_attaching 1057s === PAUSE TestApplyProToken/Success_attaching 1057s === RUN TestApplyProToken/Success_detaching 1057s === PAUSE TestApplyProToken/Success_detaching 1057s === RUN TestApplyProToken/Error_calling_pro_detach 1057s === CONT TestApplyLandscapeConfig 1057s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 1057s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 1057s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 1057s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 1057s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 1057s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 1057s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 1057s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 1057s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 1057s === PAUSE TestApplyProToken/Error_calling_pro_detach 1057s === RUN TestApplyProToken/Error_calling_pro_attach 1057s === PAUSE TestApplyProToken/Error_calling_pro_attach 1057s === CONT TestApplyProToken/Success_attaching 1057s === CONT TestApplyProToken/Error_calling_pro_attach 1057s === CONT TestApplyProToken/Error_calling_pro_detach 1057s === CONT TestApplyProToken/Success_detaching 1057s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 1057s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 1057s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyProToken: Received empty token: detaching" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1057s time="2024-07-31T05:26:40Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1057s --- PASS: TestApplyLandscapeConfig (0.00s) 1057s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (47.22s) 1057s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (51.47s) 1057s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (53.32s) 1057s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (53.52s) 1057s --- PASS: TestApplyProToken (0.01s) 1057s --- PASS: TestApplyProToken/Error_calling_pro_detach (53.28s) 1057s --- PASS: TestApplyProToken/Success_detaching (55.60s) 1057s --- PASS: TestApplyProToken/Success_attaching (62.48s) 1057s --- PASS: TestApplyProToken/Error_calling_pro_attach (63.26s) 1057s PASS 1057s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 66.280s 1094s === RUN TestNew 1094s === PAUSE TestNew 1094s === RUN TestServe 1094s === PAUSE TestServe 1094s === RUN TestServeAndQuit 1094s === PAUSE TestServeAndQuit 1094s === RUN TestReconnection 1094s === PAUSE TestReconnection 1094s === RUN TestWithProMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithProMock (0.00s) 1094s === RUN TestWithWslPathMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslPathMock (0.00s) 1094s === RUN TestWithWslInfoMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslInfoMock (0.00s) 1094s === RUN TestWithCmdExeMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithCmdExeMock (0.00s) 1094s === CONT TestNew 1094s === RUN TestNew/Error_when_WslPath_returns_error 1094s === PAUSE TestNew/Error_when_WslPath_returns_error 1094s === RUN TestNew/Success 1094s === PAUSE TestNew/Success 1094s === CONT TestNew/Error_when_WslPath_returns_error 1094s === CONT TestServeAndQuit 1094s === RUN TestServeAndQuit/Success_with_double_quit 1094s === PAUSE TestServeAndQuit/Success_with_double_quit 1094s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 1094s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 1094s === RUN TestServeAndQuit/Success_with_graceful_quit 1094s === PAUSE TestServeAndQuit/Success_with_graceful_quit 1094s === RUN TestServeAndQuit/Success_with_forceful_quit 1094s === PAUSE TestServeAndQuit/Success_with_forceful_quit 1094s === CONT TestServeAndQuit/Success_with_double_quit 1094s === CONT TestServe 1094s === RUN TestServe/No_connection_because_there_is_no_server 1094s === PAUSE TestServe/No_connection_because_there_is_no_server 1094s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 1094s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 1094s === RUN TestServe/Success 1094s === PAUSE TestServe/Success 1094s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 1094s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 1094s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 1094s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 1094s === RUN TestServe/No_connection_because_the_port_file_has_port_0 1094s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 1094s === RUN TestServe/No_connection_because_there_are_no_certificates 1094s === PAUSE TestServe/No_connection_because_there_are_no_certificates 1094s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1094s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1094s === RUN TestServe/Error_because_the_context_is_pre-cancelled 1094s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 1094s === RUN TestServe/Error_because_the_notifier_returns_an_error 1094s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 1094s === RUN TestServe/Success_with_systemd_notifier_returning_true 1094s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 1094s === RUN TestServe/No_connection_because_the_port_file_is_empty 1094s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 1094s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 1094s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 1094s === CONT TestServe/No_connection_because_there_is_no_server 1094s === CONT TestServeAndQuit/Success_with_forceful_quit 1094s === CONT TestServeAndQuit/Success_with_graceful_quit 1094s === CONT TestReconnection 1094s === RUN TestReconnection/Success_connecting_after_failing_to_connect 1094s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 1094s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 1094s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 1094s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1094s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1094s === CONT TestReconnection/Success_connecting_after_failing_to_connect 1094s time="2024-07-31T05:26:40Z" level=debug msg="Building new daemon" 1094s === CONT TestNew/Success 1094s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37789" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32935" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43611" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33581" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:26:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37099" 1094s time="2024-07-31T05:26:41Z" level=debug msg="Building new daemon" 1094s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1094s time="2024-07-31T05:27:44Z" level=debug msg="Building new daemon" 1094s --- PASS: TestNew (0.00s) 1094s --- PASS: TestNew/Success (63.78s) 1094s --- PASS: TestNew/Error_when_WslPath_returns_error (64.26s) 1094s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 1094s time="2024-07-31T05:27:45Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:27:45Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:27:45Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:45Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:45Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:27:45Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:45Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:46Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:27:46Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:46Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:46Z" 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_connect3112700711/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect3112700711/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1094s time="2024-07-31T05:27:46Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:27:46Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1094s time="2024-07-31T05:27:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34521" 1094s time="2024-07-31T05:27:47Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:47Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:47Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:27:47Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:47Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:48Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:48Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:48Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:27:48Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:48Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:27:48Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1094s time="2024-07-31T05:27:48Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33595" 1094s time="2024-07-31T05:27:48Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:27:52Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37789" 1094s time="2024-07-31T05:27:52Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:27:52Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37099" 1094s time="2024-07-31T05:27:52Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:27:52Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:27:54Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34521" 1094s time="2024-07-31T05:27:54Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:27:54Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:32935" 1094s time="2024-07-31T05:27:54Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:27:54Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:27:55Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43611" 1094s time="2024-07-31T05:27:55Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:27:55Z" 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:43611: connect: connection refused\"" 1094s time="2024-07-31T05:27:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:27:55Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:55Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:27:55Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 1094s time="2024-07-31T05:27:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35123" 1094s time="2024-07-31T05:27:55Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:27:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46355" 1094s time="2024-07-31T05:27:57Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:27:57Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:27:57Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Server: sent preface messages to all streams" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Connected ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:59Z" level=info msg="Stopping active requests." 1094s time="2024-07-31T05:27:59Z" level=warning msg="Daemon: disconnected from Windows host" 1094s time="2024-07-31T05:27:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Server: sent preface messages to all streams" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Connected ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=info msg="Stopping active requests." 1094s time="2024-07-31T05:27:59Z" level=warning msg="Daemon: disconnected from Windows host" 1094s time="2024-07-31T05:27:59Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_the_port_file_is_empty 1094s time="2024-07-31T05:27:59Z" level=debug msg="Server: sent preface messages to all streams" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Connected ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1094s time="2024-07-31T05:27:59Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:27:59Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:27:59Z" level=warning msg="Daemon: disconnected from Windows host" 1094s time="2024-07-31T05:27:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:27:59Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:59Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s === CONT TestServe/Success_with_systemd_notifier_returning_true 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36091" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34675" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Building new daemon" 1094s === CONT TestServe/Error_because_the_notifier_returns_an_error 1094s time="2024-07-31T05:27:59Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36765" 1094s time="2024-07-31T05:27:59Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43719" 1094s time="2024-07-31T05:28:00Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:28:00Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:00Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s === NAME TestServeAndQuit/Success_with_double_quit 1094s daemon_test.go:296: 1094s Error Trace: /tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon_test.go:296 1094s Error: Condition never satisfied 1094s Test: TestServeAndQuit/Success_with_double_quit 1094s Messages: Daemon never connected to agent's service 1094s time="2024-07-31T05:28:05Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s --- FAIL: TestServeAndQuit (0.00s) 1094s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (67.47s) 1094s --- PASS: TestServeAndQuit/Success_with_forceful_quit (78.38s) 1094s --- PASS: TestServeAndQuit/Success_with_graceful_quit (78.74s) 1094s --- FAIL: TestServeAndQuit/Success_with_double_quit (84.95s) 1094s time="2024-07-31T05:28:05Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:05Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s === CONT TestServe/Error_because_the_context_is_pre-cancelled 1094s time="2024-07-31T05:28:05Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35019" 1094s time="2024-07-31T05:28:05Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:07Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:08Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:28:11Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46355" 1094s time="2024-07-31T05:28:11Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:28:11Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:28:11Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:28:11Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:28:13Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43719" 1094s time="2024-07-31T05:28:13Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:28:13Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:28:13Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:28:13Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:28:15Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:15Z" 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" 1094s time="2024-07-31T05:28:15Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:15Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33595" 1094s time="2024-07-31T05:28:15Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file3634823159/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 1094s time="2024-07-31T05:28:15Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:15Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:15Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:15Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 1094s time="2024-07-31T05:28:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33153" 1094s time="2024-07-31T05:28:15Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:16Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:16Z" 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" 1094s time="2024-07-31T05:28:16Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:28:16Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:16Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:16Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_there_are_no_certificates 1094s time="2024-07-31T05:28:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40113" 1094s time="2024-07-31T05:28:16Z" level=debug msg="Building new daemon" 1094s === NAME TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1094s daemon_test.go:375: 1094s Error Trace: /tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon_test.go:375 1094s Error: Condition never satisfied 1094s Test: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1094s Messages: Daemon never connected to agent's service 1094s time="2024-07-31T05:28:22Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:22Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:22Z" level=info msg="Stopping active requests." 1094s time="2024-07-31T05:28:22Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:22Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s === NAME TestReconnection/Success_connecting_after_previous_connection_dropped 1094s daemon_test.go:375: 1094s Error Trace: /tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/daemon/daemon_test.go:375 1094s Error: Condition never satisfied 1094s Test: TestReconnection/Success_connecting_after_previous_connection_dropped 1094s Messages: Daemon never connected to agent's service 1094s time="2024-07-31T05:28:24Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:24Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:24Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:24Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:24Z" level=info msg="Stopping active requests." 1094s time="2024-07-31T05:28:24Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:28:24Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_the_port_file_has_port_0 1094s time="2024-07-31T05:28:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33119" 1094s time="2024-07-31T05:28:24Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:32Z" level=debug msg="Ready state sent to systemd" 1094s time="2024-07-31T05:28:32Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:32Z" level=debug msg="Updated systemd status to \"Connecting\"" 1094s time="2024-07-31T05:28:34Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:34Z" 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" 1094s time="2024-07-31T05:28:34Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:35Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 1094s time="2024-07-31T05:28:35Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:35Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:35Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/Success 1094s time="2024-07-31T05:28:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42831" 1094s time="2024-07-31T05:28:35Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:35Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:35Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:35Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 1094s time="2024-07-31T05:28:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35915" 1094s time="2024-07-31T05:28:35Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:35Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:35Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:35Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:35Z" 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" 1094s time="2024-07-31T05:28:35Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:28:35Z" level=debug msg="All connections have now ended." 1094s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 1094s time="2024-07-31T05:28:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39479" 1094s time="2024-07-31T05:28:36Z" level=debug msg="Building new daemon" 1094s time="2024-07-31T05:28:37Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:28:37Z" level=debug msg="All connections have now ended." 1094s --- FAIL: TestReconnection (0.01s) 1094s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (78.46s) 1094s --- FAIL: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (39.99s) 1094s --- FAIL: TestReconnection/Success_connecting_after_previous_connection_dropped (52.08s) 1094s time="2024-07-31T05:28:39Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34675" 1094s time="2024-07-31T05:28:39Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:28:39Z" level=debug msg="Updated systemd status to \"Connected\"" 1094s time="2024-07-31T05:28:39Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:28:39Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:28:41Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:43Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:43Z" 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" 1094s time="2024-07-31T05:28:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:43Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:43Z" 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" 1094s time="2024-07-31T05:28:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:43Z" level=debug msg="Server: sent preface messages to all streams" 1094s time="2024-07-31T05:28:43Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1094s time="2024-07-31T05:28:43Z" level=info msg="MockWindowsAgent: Connected ready" 1094s time="2024-07-31T05:28:43Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:44Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:44Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:44Z" 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" 1094s time="2024-07-31T05:28:44Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:28:44Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:44Z" 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_exist2423967874/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2423967874/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1094s time="2024-07-31T05:28:44Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:44Z" 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" 1094s time="2024-07-31T05:28:44Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:28:44Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:44Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:44Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:44Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40113" 1094s time="2024-07-31T05:28:44Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates1803257806/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 1094s time="2024-07-31T05:28:44Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:45Z" level=info msg="Daemon: connecting to Windows Agent" 1094s time="2024-07-31T05:28:45Z" 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_exist2423967874/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2423967874/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1094s time="2024-07-31T05:28:45Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1094s time="2024-07-31T05:28:45Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:45Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:45Z" level=warning msg="Daemon: disconnected from Windows host" 1094s time="2024-07-31T05:28:45Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:45Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1094s time="2024-07-31T05:28:45Z" level=debug msg="Updated systemd status to \"Stopped\"" 1094s time="2024-07-31T05:28:45Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:45Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:45Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:45Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:45Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:45Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:45Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:46Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:46Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:46Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:46Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:46Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:46Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:46Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42831" 1094s time="2024-07-31T05:28:46Z" level=info msg="Daemon: completed connection to Windows Agent" 1094s time="2024-07-31T05:28:46Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1094s time="2024-07-31T05:28:46Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1094s time="2024-07-31T05:28:47Z" level=debug msg="Server: sent preface messages to all streams" 1094s time="2024-07-31T05:28:47Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1094s time="2024-07-31T05:28:47Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1094s time="2024-07-31T05:28:47Z" level=info msg="MockWindowsAgent: Connected ready" 1094s time="2024-07-31T05:28:49Z" level=info msg="Stopping daemon requested." 1094s time="2024-07-31T05:28:49Z" level=info msg="Waiting for active requests to close." 1094s time="2024-07-31T05:28:49Z" level=warning msg="Daemon: disconnected from Windows host" 1094s time="2024-07-31T05:28:49Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1094s time="2024-07-31T05:28:49Z" level=debug msg="All connections have now ended." 1094s time="2024-07-31T05:28:49Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:49Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:28:49Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1094s --- PASS: TestServe (0.00s) 1094s --- PASS: TestServe/No_connection_because_there_is_no_server (74.46s) 1094s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (27.32s) 1094s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (20.77s) 1094s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (35.97s) 1094s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (29.98s) 1094s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (36.66s) 1094s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (19.31s) 1094s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (28.70s) 1094s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (46.02s) 1094s --- PASS: TestServe/No_connection_because_there_are_no_certificates (29.36s) 1094s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (10.32s) 1094s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (10.14s) 1094s --- PASS: TestServe/Success (13.55s) 1094s FAIL 1094s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 131.666s 1094s === RUN TestConnect 1094s === PAUSE TestConnect 1094s === RUN TestSendAndRecv 1094s --- PASS: TestSendAndRecv (1.15s) 1094s === RUN TestServe 1094s === PAUSE TestServe 1094s === RUN TestStop 1094s === PAUSE TestStop 1094s === RUN TestWithProMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithProMock (0.00s) 1094s === RUN TestWithWslPathMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslPathMock (0.00s) 1094s === RUN TestWithWslInfoMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslInfoMock (0.00s) 1094s === RUN TestWithCmdExeMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithCmdExeMock (0.00s) 1094s === CONT TestConnect 1094s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 1094s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 1094s === RUN TestConnect/Success 1094s === PAUSE TestConnect/Success 1094s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 1094s === CONT TestStop 1094s === CONT TestServe 1094s === CONT TestConnect/Success 1094s time="2024-07-31T05:26:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33831" 1094s time="2024-07-31T05:26:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33277" 1094s --- PASS: TestConnect (0.00s) 1094s --- PASS: TestConnect/Success (0.31s) 1094s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.00s) 1094s === NAME TestServe 1094s server_test.go:41: 1094s Error Trace: /tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/streams/server_test.go:41 1094s Error: Condition never satisfied 1094s Test: TestServe 1094s Messages: Setup: Agent service never became ready 1094s time="2024-07-31T05:27:10Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:10Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s time="2024-07-31T05:27:10Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s --- FAIL: TestServe (21.55s) 1094s === NAME TestStop 1094s server_test.go:116: 1094s Error Trace: /tmp/autopkgtest.Wtvhwo/build.t6Y/src/internal/streams/server_test.go:116 1094s Error: Condition never satisfied 1094s Test: TestStop 1094s Messages: Setup: Agent service never became ready 1094s time="2024-07-31T05:27:10Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1094s --- FAIL: TestStop (21.61s) 1094s FAIL 1094s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 33.014s 1094s === RUN TestInfo 1094s === PAUSE TestInfo 1094s === RUN TestWslDistroName 1094s === PAUSE TestWslDistroName 1094s === RUN TestUserProfileDir 1094s === PAUSE TestUserProfileDir 1094s === RUN TestProStatus 1094s === PAUSE TestProStatus 1094s === RUN TestProAttach 1094s === PAUSE TestProAttach 1094s === RUN TestProDetach 1094s === PAUSE TestProDetach 1094s === RUN TestLandscapeEnable 1094s === PAUSE TestLandscapeEnable 1094s === RUN TestWindowsHostAddress 1094s === PAUSE TestWindowsHostAddress 1094s === RUN TestLandscapeDisable 1094s === PAUSE TestLandscapeDisable 1094s === RUN TestRealBackend 1094s === PAUSE TestRealBackend 1094s === RUN TestWithProMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithProMock (0.00s) 1094s === RUN TestWithLandscapeConfigMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1094s === RUN TestWithWslPathMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslPathMock (0.00s) 1094s === RUN TestWithWslInfoMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithWslInfoMock (0.00s) 1094s === RUN TestWithCmdExeMock 1094s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1094s --- SKIP: TestWithCmdExeMock (0.00s) 1094s === CONT TestInfo 1094s === RUN TestInfo/Success 1094s === PAUSE TestInfo/Success 1094s === RUN TestInfo/Error_when_WslDistroName_fails 1094s === PAUSE TestInfo/Error_when_WslDistroName_fails 1094s === RUN TestInfo/Error_when_pro_status_command_fails 1094s === PAUSE TestInfo/Error_when_pro_status_command_fails 1094s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 1094s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 1094s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 1094s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 1094s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1094s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1094s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 1094s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 1094s === CONT TestInfo/Success 1094s === CONT TestUserProfileDir 1094s === RUN TestUserProfileDir/Error_on_wslpath_error 1094s === PAUSE TestUserProfileDir/Error_on_wslpath_error 1094s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 1094s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 1094s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1094s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1094s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1094s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1094s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1094s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1094s === RUN TestUserProfileDir/Error_on_cmd.exe_error 1094s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 1094s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1094s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1094s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1094s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1094s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1094s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1094s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1094s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1094s === CONT TestUserProfileDir/Error_on_wslpath_error 1094s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1094s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1094s === CONT TestRealBackend 1094s === CONT TestLandscapeDisable 1094s === RUN TestLandscapeDisable/Success 1094s === PAUSE TestLandscapeDisable/Success 1094s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1094s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1094s === CONT TestWindowsHostAddress 1094s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1094s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1094s === RUN TestWindowsHostAddress/Success_without_NAT 1094s === PAUSE TestWindowsHostAddress/Success_without_NAT 1094s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1094s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1094s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1094s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1094s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1094s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1094s === CONT TestProDetach 1094s === RUN TestProDetach/success_on_unattached_distro 1094s === PAUSE TestProDetach/success_on_unattached_distro 1094s === RUN TestProDetach/success_on_attached_distro 1094s === PAUSE TestProDetach/success_on_attached_distro 1094s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1094s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1094s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 1094s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 1094s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1094s === CONT TestLandscapeEnable 1094s === CONT TestProAttach 1094s === RUN TestProAttach/success 1094s === PAUSE TestProAttach/success 1094s === RUN TestProAttach/error_on_'pro_attach'_error 1094s === PAUSE TestProAttach/error_on_'pro_attach'_error 1094s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1094s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1094s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1094s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1094s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1094s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1094s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1094s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1094s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1094s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1094s === RUN TestLandscapeEnable/Success 1094s === PAUSE TestLandscapeEnable/Success 1094s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1094s === RUN TestLandscapeEnable/Success_overriding_computer_title 1094s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 1094s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1094s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1094s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1094s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1094s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1094s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1094s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1094s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1094s === CONT TestUserProfileDir/Error_on_cmd.exe_error 1094s === CONT TestProStatus 1094s === RUN TestProStatus/success_on_unattached_distro 1094s === PAUSE TestProStatus/success_on_unattached_distro 1094s === RUN TestProStatus/success_on_attached_distro 1094s === PAUSE TestProStatus/success_on_attached_distro 1094s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 1094s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 1094s === RUN TestProStatus/error_on_'pro_attach'_error 1094s === PAUSE TestProStatus/error_on_'pro_attach'_error 1094s --- PASS: TestRealBackend (0.00s) 1094s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1094s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1094s === NAME TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1094s system_test.go:205: Removing default proc/mounts 1094s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1094s system_test.go:205: Removing default proc/mounts 1094s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 1094s === CONT TestWslDistroName 1094s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1094s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1094s === RUN TestWslDistroName/Success_using_wslpath 1094s === PAUSE TestWslDistroName/Success_using_wslpath 1094s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1094s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1094s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1094s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1094s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 1094s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1094s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 1094s === CONT TestInfo/Error_when_pro_status_command_fails 1094s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 1094s === CONT TestInfo/Error_when_WslDistroName_fails 1094s === CONT TestLandscapeDisable/Success 1094s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1094s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1094s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1094s --- PASS: TestUserProfileDir (0.00s) 1094s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.04s) 1094s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.01s) 1094s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 1094s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (22.69s) 1094s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (30.63s) 1094s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (30.90s) 1094s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (31.11s) 1094s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (32.64s) 1094s --- PASS: TestUserProfileDir/Error_on_wslpath_error (32.89s) 1094s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (14.15s) 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1094s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1094s --- PASS: TestLandscapeDisable (0.15s) 1094s --- PASS: TestLandscapeDisable/Success (8.15s) 1094s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (7.11s) 1094s === CONT TestWindowsHostAddress/Success_without_NAT 1094s --- PASS: TestInfo (0.00s) 1094s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (22.45s) 1094s --- PASS: TestInfo/Success (24.86s) 1094s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (8.05s) 1094s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (9.41s) 1094s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (7.28s) 1094s --- PASS: TestInfo/Error_when_pro_status_command_fails (7.65s) 1094s --- PASS: TestInfo/Error_when_WslDistroName_fails (9.07s) 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1094s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1094s === CONT TestProAttach/success 1094s === CONT TestProDetach/success_on_unattached_distro 1094s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1094s === CONT TestProAttach/error_on_'pro_attach'_error 1094s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 1094s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1094s === CONT TestProDetach/success_on_attached_distro 1094s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1094s time="2024-07-31T05:28:13Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1094s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1094s --- PASS: TestWindowsHostAddress (0.00s) 1094s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (8.65s) 1094s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (10.11s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (9.99s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (10.80s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (14.70s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (11.70s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (13.76s) 1094s --- PASS: TestWindowsHostAddress/Success_without_NAT (15.62s) 1094s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (16.45s) 1094s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (17.11s) 1094s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1094s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1094s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1094s === CONT TestLandscapeEnable/Success_overriding_computer_title 1094s time="2024-07-31T05:28:24Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 1094s --- PASS: TestProAttach (0.00s) 1094s --- PASS: TestProAttach/success (7.38s) 1094s --- PASS: TestProAttach/error_on_'pro_attach'_error (13.05s) 1094s === CONT TestLandscapeEnable/Success 1094s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1094s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1094s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1094s time="2024-07-31T05:28:31Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1094s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1094s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 1094s === CONT TestProStatus/success_on_unattached_distro 1094s --- PASS: TestProDetach (0.00s) 1094s --- PASS: TestProDetach/success_on_unattached_distro (8.25s) 1094s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (13.03s) 1094s --- PASS: TestProDetach/success_on_attached_distro (18.04s) 1094s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (18.86s) 1094s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (21.29s) 1094s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 1094s === CONT TestProStatus/error_on_'pro_attach'_error 1094s === NAME TestLandscapeEnable/Success_overriding_computer_title 1094s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 1094s === CONT TestProStatus/success_on_attached_distro 1094s === NAME TestLandscapeEnable/Success 1094s system_test.go:456: testdata/TestLandscapeEnable/golden/success 1094s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1094s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1094s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1094s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1094s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 1094s === CONT TestWslDistroName/Success_using_wslpath 1094s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1094s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1094s --- PASS: TestLandscapeEnable (0.05s) 1094s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 1094s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 1094s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.00s) 1094s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (20.96s) 1094s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (22.59s) 1094s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (14.26s) 1094s --- PASS: TestLandscapeEnable/Success (14.25s) 1094s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (8.24s) 1094s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (25.18s) 1094s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (8.63s) 1094s --- PASS: TestProStatus (0.00s) 1094s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (5.98s) 1094s --- PASS: TestProStatus/success_on_unattached_distro (6.17s) 1094s --- PASS: TestProStatus/error_on_'pro_attach'_error (4.00s) 1094s --- PASS: TestProStatus/success_on_attached_distro (4.44s) 1094s --- PASS: TestWslDistroName (0.00s) 1094s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.04s) 1094s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (3.55s) 1094s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (4.45s) 1094s --- PASS: TestWslDistroName/Success_using_wslpath (3.33s) 1094s PASS 1094s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 98.430s 1094s FAIL 1094s autopkgtest [05:28:49]: test command1: -----------------------] 1099s autopkgtest [05:28:54]: test command1: - - - - - - - - - - results - - - - - - - - - - 1099s command1 FAIL non-zero exit status 1 1103s autopkgtest [05:28:58]: @@@@@@@@@@@@@@@@@@@@ summary 1103s command1 FAIL non-zero exit status 1