0s autopkgtest [02:40:48]: starting date and time: 2024-05-24 02:40:48+0000 0s autopkgtest [02:40:48]: git checkout: 699e7f9f ssh-setup/nova: explicitely set 'fqdn' in cloud-init 0s autopkgtest [02:40:48]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dobohpfc/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ubuntu-advantage-tools --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/32.2~24.04' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@lcy02-129.secgroup --name adt-noble-amd64-wsl-pro-service-20240524-020533-juju-7f2275-prod-proposed-migration-environment-2-4f354c29-86cb-4770-ae0a-b86c6bc436e6 --image adt/ubuntu-noble-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 1519s autopkgtest [03:06:07]: testbed dpkg architecture: amd64 1519s autopkgtest [03:06:07]: testbed apt version: 2.7.14build2 1519s autopkgtest [03:06:07]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1520s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [256 kB] 1520s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [10.9 kB] 1520s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [3828 B] 1520s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2080 B] 1520s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [26.8 kB] 1520s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main i386 Packages [53.1 kB] 1520s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 Packages [87.9 kB] 1520s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 c-n-f Metadata [3508 B] 1520s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 Packages [23.7 kB] 1520s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 c-n-f Metadata [116 B] 1520s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe i386 Packages [30.1 kB] 1520s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 Packages [49.9 kB] 1520s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 c-n-f Metadata [9396 B] 1520s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 Packages [2968 B] 1520s Get:15 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 c-n-f Metadata [196 B] 1522s Fetched 560 kB in 0s (2428 kB/s) 1522s Reading package lists... 1523s Reading package lists... 1523s Building dependency tree... 1523s Reading state information... 1524s Calculating upgrade... 1524s The following packages will be upgraded: 1524s dhcpcd-base initramfs-tools initramfs-tools-bin initramfs-tools-core 1524s ubuntu-pro-client ubuntu-pro-client-l10n 1524s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1524s Need to get 543 kB of archives. 1524s After this operation, 106 kB of additional disk space will be used. 1524s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 dhcpcd-base amd64 1:10.0.6-1ubuntu3.1 [215 kB] 1524s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 ubuntu-pro-client-l10n amd64 32.2~24.04 [19.4 kB] 1524s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 ubuntu-pro-client amd64 32.2~24.04 [229 kB] 1524s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 initramfs-tools all 0.142ubuntu25.1 [9078 B] 1524s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 initramfs-tools-core all 0.142ubuntu25.1 [49.9 kB] 1524s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 initramfs-tools-bin amd64 0.142ubuntu25.1 [20.9 kB] 1524s Fetched 543 kB in 0s (11.1 MB/s) 1525s (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 ... 73890 files and directories currently installed.) 1525s Preparing to unpack .../0-dhcpcd-base_1%3a10.0.6-1ubuntu3.1_amd64.deb ... 1525s Unpacking dhcpcd-base (1:10.0.6-1ubuntu3.1) over (1:10.0.6-1ubuntu3) ... 1525s Preparing to unpack .../1-ubuntu-pro-client-l10n_32.2~24.04_amd64.deb ... 1525s Unpacking ubuntu-pro-client-l10n (32.2~24.04) over (31.2.3) ... 1525s Preparing to unpack .../2-ubuntu-pro-client_32.2~24.04_amd64.deb ... 1525s Unpacking ubuntu-pro-client (32.2~24.04) over (31.2.3) ... 1525s Preparing to unpack .../3-initramfs-tools_0.142ubuntu25.1_all.deb ... 1525s Unpacking initramfs-tools (0.142ubuntu25.1) over (0.142ubuntu25) ... 1525s Preparing to unpack .../4-initramfs-tools-core_0.142ubuntu25.1_all.deb ... 1525s Unpacking initramfs-tools-core (0.142ubuntu25.1) over (0.142ubuntu25) ... 1525s Preparing to unpack .../5-initramfs-tools-bin_0.142ubuntu25.1_amd64.deb ... 1525s Unpacking initramfs-tools-bin (0.142ubuntu25.1) over (0.142ubuntu25) ... 1525s Setting up dhcpcd-base (1:10.0.6-1ubuntu3.1) ... 1525s Setting up ubuntu-pro-client (32.2~24.04) ... 1525s Installing new version of config file /etc/apparmor.d/ubuntu_pro_apt_news ... 1527s Setting up initramfs-tools-bin (0.142ubuntu25.1) ... 1527s Setting up ubuntu-pro-client-l10n (32.2~24.04) ... 1527s Setting up initramfs-tools-core (0.142ubuntu25.1) ... 1527s Setting up initramfs-tools (0.142ubuntu25.1) ... 1527s update-initramfs: deferring update (trigger activated) 1527s Processing triggers for man-db (2.12.0-4build2) ... 1528s Processing triggers for initramfs-tools (0.142ubuntu25.1) ... 1528s update-initramfs: Generating /boot/initrd.img-6.8.0-31-generic 1528s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 1537s Reading package lists... 1537s Building dependency tree... 1537s Reading state information... 1537s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1538s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 1538s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 1538s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 1538s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 1539s Reading package lists... 1539s Reading package lists... 1539s Building dependency tree... 1539s Reading state information... 1539s Calculating upgrade... 1539s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1539s Reading package lists... 1540s Building dependency tree... 1540s Reading state information... 1540s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1540s autopkgtest [03:06:28]: rebooting testbed after setup commands that affected boot 1543s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1554s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1558s autopkgtest [03:06:46]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 1559s autopkgtest [03:06:47]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 1560s Get:1 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (dsc) [1420 B] 1560s Get:2 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (tar) [1762 kB] 1560s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 1560s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 1560s gpgv: Can't check signature: No public key 1560s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 1561s autopkgtest [03:06:49]: testing package wsl-pro-service version 0.1.4 1561s autopkgtest [03:06:49]: build not needed 1561s autopkgtest [03:06:49]: test command1: preparing testbed 1563s Reading package lists... 1563s Building dependency tree... 1563s Reading state information... 1563s Starting pkgProblemResolver with broken count: 0 1563s Starting 2 pkgProblemResolver with broken count: 0 1563s Done 1563s The following additional packages will be installed: 1563s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 1563s cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 1563s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 1563s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 gcc-13-base 1563s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 1563s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 1563s libasan8 libatomic1 libcc1-0 libdebhelper-perl 1563s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libhwasan0 libisl23 1563s libitm1 liblsan0 libmpc3 libquadmath0 libstdc++-13-dev libsub-override-perl 1563s libtool libtsan2 libubsan1 m4 po-debconf 1563s Suggested packages: 1563s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 1563s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 1563s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-x86-64-linux-gnu 1563s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 1563s subversion libstdc++-13-doc libtool-doc gfortran | fortran95-compiler 1563s gcj-jdk m4-doc libmail-box-perl 1563s Recommended packages: 1563s pkg-config libarchive-cpio-perl libltdl-dev libmail-sendmail-perl 1564s The following NEW packages will be installed: 1564s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 1564s cpp cpp-13 cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit 1564s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 1564s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 gcc-13-base 1564s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 1564s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 1564s libasan8 libatomic1 libcc1-0 libdebhelper-perl 1564s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libhwasan0 libisl23 1564s libitm1 liblsan0 libmpc3 libquadmath0 libstdc++-13-dev libsub-override-perl 1564s libtool libtsan2 libubsan1 m4 po-debconf 1564s 0 upgraded, 53 newly installed, 0 to remove and 0 not upgraded. 1564s Need to get 112 MB/112 MB of archives. 1564s After this operation, 462 MB of additional disk space will be used. 1564s Get:1 /tmp/autopkgtest.iliS7S/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [756 B] 1564s Get:2 http://ftpmaster.internal/ubuntu noble/main amd64 m4 amd64 1.4.19-4build1 [244 kB] 1564s Get:3 http://ftpmaster.internal/ubuntu noble/main amd64 autoconf all 2.71-3 [339 kB] 1564s Get:4 http://ftpmaster.internal/ubuntu noble/main amd64 autotools-dev all 20220109.1 [44.9 kB] 1564s Get:5 http://ftpmaster.internal/ubuntu noble/main amd64 automake all 1:1.16.5-1.3ubuntu1 [558 kB] 1564s Get:6 http://ftpmaster.internal/ubuntu noble/main amd64 autopoint all 0.21-14ubuntu2 [422 kB] 1564s Get:7 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13-base amd64 13.2.0-23ubuntu4 [49.0 kB] 1564s Get:8 http://ftpmaster.internal/ubuntu noble/main amd64 libisl23 amd64 0.26-3build1 [680 kB] 1564s Get:9 http://ftpmaster.internal/ubuntu noble/main amd64 libmpc3 amd64 1.3.1-1build1 [54.5 kB] 1564s Get:10 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13-x86-64-linux-gnu amd64 13.2.0-23ubuntu4 [11.2 MB] 1564s Get:11 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13 amd64 13.2.0-23ubuntu4 [1032 B] 1564s Get:12 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [5326 B] 1564s Get:13 http://ftpmaster.internal/ubuntu noble/main amd64 cpp amd64 4:13.2.0-7ubuntu1 [22.4 kB] 1564s Get:14 http://ftpmaster.internal/ubuntu noble/main amd64 libcc1-0 amd64 14-20240412-0ubuntu1 [47.7 kB] 1564s Get:15 http://ftpmaster.internal/ubuntu noble/main amd64 libgomp1 amd64 14-20240412-0ubuntu1 [147 kB] 1564s Get:16 http://ftpmaster.internal/ubuntu noble/main amd64 libitm1 amd64 14-20240412-0ubuntu1 [28.9 kB] 1564s Get:17 http://ftpmaster.internal/ubuntu noble/main amd64 libatomic1 amd64 14-20240412-0ubuntu1 [10.4 kB] 1564s Get:18 http://ftpmaster.internal/ubuntu noble/main amd64 libasan8 amd64 14-20240412-0ubuntu1 [3024 kB] 1564s Get:19 http://ftpmaster.internal/ubuntu noble/main amd64 liblsan0 amd64 14-20240412-0ubuntu1 [1313 kB] 1564s Get:20 http://ftpmaster.internal/ubuntu noble/main amd64 libtsan2 amd64 14-20240412-0ubuntu1 [2736 kB] 1564s Get:21 http://ftpmaster.internal/ubuntu noble/main amd64 libubsan1 amd64 14-20240412-0ubuntu1 [1175 kB] 1564s Get:22 http://ftpmaster.internal/ubuntu noble/main amd64 libhwasan0 amd64 14-20240412-0ubuntu1 [1632 kB] 1564s Get:23 http://ftpmaster.internal/ubuntu noble/main amd64 libquadmath0 amd64 14-20240412-0ubuntu1 [153 kB] 1564s Get:24 http://ftpmaster.internal/ubuntu noble/main amd64 libgcc-13-dev amd64 13.2.0-23ubuntu4 [2688 kB] 1564s Get:25 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13-x86-64-linux-gnu amd64 13.2.0-23ubuntu4 [21.9 MB] 1564s Get:26 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13 amd64 13.2.0-23ubuntu4 [482 kB] 1564s Get:27 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [1212 B] 1564s Get:28 http://ftpmaster.internal/ubuntu noble/main amd64 gcc amd64 4:13.2.0-7ubuntu1 [5018 B] 1564s Get:29 http://ftpmaster.internal/ubuntu noble/main amd64 libstdc++-13-dev amd64 13.2.0-23ubuntu4 [2399 kB] 1564s Get:30 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13-x86-64-linux-gnu amd64 13.2.0-23ubuntu4 [12.5 MB] 1564s Get:31 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13 amd64 13.2.0-23ubuntu4 [14.5 kB] 1564s Get:32 http://ftpmaster.internal/ubuntu noble/main amd64 g++-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [964 B] 1564s Get:33 http://ftpmaster.internal/ubuntu noble/main amd64 g++ amd64 4:13.2.0-7ubuntu1 [1100 B] 1564s Get:34 http://ftpmaster.internal/ubuntu noble/main amd64 build-essential amd64 12.10ubuntu1 [4928 B] 1564s Get:35 http://ftpmaster.internal/ubuntu noble/main amd64 libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 1564s Get:36 http://ftpmaster.internal/ubuntu noble/main amd64 libtool all 2.4.7-7build1 [166 kB] 1564s Get:37 http://ftpmaster.internal/ubuntu noble/main amd64 dh-autoreconf all 20 [16.1 kB] 1564s Get:38 http://ftpmaster.internal/ubuntu noble/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 1564s Get:39 http://ftpmaster.internal/ubuntu noble/main amd64 libsub-override-perl all 0.10-1 [10.0 kB] 1564s Get:40 http://ftpmaster.internal/ubuntu noble/main amd64 libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 1564s Get:41 http://ftpmaster.internal/ubuntu noble/main amd64 dh-strip-nondeterminism all 1.13.1-1 [5362 B] 1564s Get:42 http://ftpmaster.internal/ubuntu noble/main amd64 debugedit amd64 1:5.0-5build2 [46.1 kB] 1564s Get:43 http://ftpmaster.internal/ubuntu noble/main amd64 dwz amd64 0.15-1build6 [115 kB] 1564s Get:44 http://ftpmaster.internal/ubuntu noble/main amd64 gettext amd64 0.21-14ubuntu2 [864 kB] 1564s Get:45 http://ftpmaster.internal/ubuntu noble/main amd64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 1564s Get:46 http://ftpmaster.internal/ubuntu noble/main amd64 po-debconf all 1.0.21+nmu1 [233 kB] 1564s Get:47 http://ftpmaster.internal/ubuntu noble/main amd64 debhelper all 13.14.1ubuntu5 [869 kB] 1564s Get:48 http://ftpmaster.internal/ubuntu noble-updates/universe amd64 dh-apport all 2.28.1-0ubuntu3 [17.2 kB] 1564s Get:49 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-src all 1.22.2-2 [19.7 MB] 1564s Get:50 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-go amd64 1.22.2-2 [25.9 MB] 1565s Get:51 http://ftpmaster.internal/ubuntu noble/main amd64 golang-src all 2:1.22~2build1 [5078 B] 1565s Get:52 http://ftpmaster.internal/ubuntu noble/main amd64 golang-go amd64 2:1.22~2build1 [43.9 kB] 1565s Get:53 http://ftpmaster.internal/ubuntu noble/main amd64 dh-golang all 1.62 [25.2 kB] 1565s Fetched 112 MB in 1s (95.0 MB/s) 1565s Selecting previously unselected package m4. 1565s (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 ... 73917 files and directories currently installed.) 1565s Preparing to unpack .../00-m4_1.4.19-4build1_amd64.deb ... 1565s Unpacking m4 (1.4.19-4build1) ... 1565s Selecting previously unselected package autoconf. 1565s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 1565s Unpacking autoconf (2.71-3) ... 1565s Selecting previously unselected package autotools-dev. 1565s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 1565s Unpacking autotools-dev (20220109.1) ... 1565s Selecting previously unselected package automake. 1565s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 1566s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 1566s Selecting previously unselected package autopoint. 1566s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 1566s Unpacking autopoint (0.21-14ubuntu2) ... 1566s Selecting previously unselected package gcc-13-base:amd64. 1566s Preparing to unpack .../05-gcc-13-base_13.2.0-23ubuntu4_amd64.deb ... 1566s Unpacking gcc-13-base:amd64 (13.2.0-23ubuntu4) ... 1566s Selecting previously unselected package libisl23:amd64. 1566s Preparing to unpack .../06-libisl23_0.26-3build1_amd64.deb ... 1566s Unpacking libisl23:amd64 (0.26-3build1) ... 1566s Selecting previously unselected package libmpc3:amd64. 1566s Preparing to unpack .../07-libmpc3_1.3.1-1build1_amd64.deb ... 1566s Unpacking libmpc3:amd64 (1.3.1-1build1) ... 1566s Selecting previously unselected package cpp-13-x86-64-linux-gnu. 1566s Preparing to unpack .../08-cpp-13-x86-64-linux-gnu_13.2.0-23ubuntu4_amd64.deb ... 1566s Unpacking cpp-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1566s Selecting previously unselected package cpp-13. 1566s Preparing to unpack .../09-cpp-13_13.2.0-23ubuntu4_amd64.deb ... 1566s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 1566s Selecting previously unselected package cpp-x86-64-linux-gnu. 1566s Preparing to unpack .../10-cpp-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 1566s Unpacking cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1566s Selecting previously unselected package cpp. 1566s Preparing to unpack .../11-cpp_4%3a13.2.0-7ubuntu1_amd64.deb ... 1566s Unpacking cpp (4:13.2.0-7ubuntu1) ... 1566s Selecting previously unselected package libcc1-0:amd64. 1566s Preparing to unpack .../12-libcc1-0_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libcc1-0:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libgomp1:amd64. 1566s Preparing to unpack .../13-libgomp1_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libgomp1:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libitm1:amd64. 1566s Preparing to unpack .../14-libitm1_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libitm1:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libatomic1:amd64. 1566s Preparing to unpack .../15-libatomic1_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libatomic1:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libasan8:amd64. 1566s Preparing to unpack .../16-libasan8_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libasan8:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package liblsan0:amd64. 1566s Preparing to unpack .../17-liblsan0_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking liblsan0:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libtsan2:amd64. 1566s Preparing to unpack .../18-libtsan2_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libtsan2:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libubsan1:amd64. 1566s Preparing to unpack .../19-libubsan1_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libubsan1:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libhwasan0:amd64. 1566s Preparing to unpack .../20-libhwasan0_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libhwasan0:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libquadmath0:amd64. 1566s Preparing to unpack .../21-libquadmath0_14-20240412-0ubuntu1_amd64.deb ... 1566s Unpacking libquadmath0:amd64 (14-20240412-0ubuntu1) ... 1566s Selecting previously unselected package libgcc-13-dev:amd64. 1566s Preparing to unpack .../22-libgcc-13-dev_13.2.0-23ubuntu4_amd64.deb ... 1566s Unpacking libgcc-13-dev:amd64 (13.2.0-23ubuntu4) ... 1566s Selecting previously unselected package gcc-13-x86-64-linux-gnu. 1566s Preparing to unpack .../23-gcc-13-x86-64-linux-gnu_13.2.0-23ubuntu4_amd64.deb ... 1566s Unpacking gcc-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1567s Selecting previously unselected package gcc-13. 1567s Preparing to unpack .../24-gcc-13_13.2.0-23ubuntu4_amd64.deb ... 1567s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 1567s Selecting previously unselected package gcc-x86-64-linux-gnu. 1567s Preparing to unpack .../25-gcc-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 1567s Unpacking gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1567s Selecting previously unselected package gcc. 1567s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_amd64.deb ... 1567s Unpacking gcc (4:13.2.0-7ubuntu1) ... 1567s Selecting previously unselected package libstdc++-13-dev:amd64. 1567s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-23ubuntu4_amd64.deb ... 1567s Unpacking libstdc++-13-dev:amd64 (13.2.0-23ubuntu4) ... 1567s Selecting previously unselected package g++-13-x86-64-linux-gnu. 1567s Preparing to unpack .../28-g++-13-x86-64-linux-gnu_13.2.0-23ubuntu4_amd64.deb ... 1567s Unpacking g++-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1567s Selecting previously unselected package g++-13. 1567s Preparing to unpack .../29-g++-13_13.2.0-23ubuntu4_amd64.deb ... 1567s Unpacking g++-13 (13.2.0-23ubuntu4) ... 1567s Selecting previously unselected package g++-x86-64-linux-gnu. 1567s Preparing to unpack .../30-g++-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 1567s Unpacking g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1567s Selecting previously unselected package g++. 1567s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_amd64.deb ... 1567s Unpacking g++ (4:13.2.0-7ubuntu1) ... 1567s Selecting previously unselected package build-essential. 1567s Preparing to unpack .../32-build-essential_12.10ubuntu1_amd64.deb ... 1567s Unpacking build-essential (12.10ubuntu1) ... 1567s Selecting previously unselected package libdebhelper-perl. 1567s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 1567s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 1567s Selecting previously unselected package libtool. 1567s Preparing to unpack .../34-libtool_2.4.7-7build1_all.deb ... 1567s Unpacking libtool (2.4.7-7build1) ... 1567s Selecting previously unselected package dh-autoreconf. 1567s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 1567s Unpacking dh-autoreconf (20) ... 1568s Selecting previously unselected package libarchive-zip-perl. 1568s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 1568s Unpacking libarchive-zip-perl (1.68-1) ... 1568s Selecting previously unselected package libsub-override-perl. 1568s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 1568s Unpacking libsub-override-perl (0.10-1) ... 1568s Selecting previously unselected package libfile-stripnondeterminism-perl. 1568s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 1568s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 1568s Selecting previously unselected package dh-strip-nondeterminism. 1568s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 1568s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 1568s Selecting previously unselected package debugedit. 1568s Preparing to unpack .../40-debugedit_1%3a5.0-5build2_amd64.deb ... 1568s Unpacking debugedit (1:5.0-5build2) ... 1568s Selecting previously unselected package dwz. 1568s Preparing to unpack .../41-dwz_0.15-1build6_amd64.deb ... 1568s Unpacking dwz (0.15-1build6) ... 1568s Selecting previously unselected package gettext. 1568s Preparing to unpack .../42-gettext_0.21-14ubuntu2_amd64.deb ... 1568s Unpacking gettext (0.21-14ubuntu2) ... 1568s Selecting previously unselected package intltool-debian. 1568s Preparing to unpack .../43-intltool-debian_0.35.0+20060710.6_all.deb ... 1568s Unpacking intltool-debian (0.35.0+20060710.6) ... 1568s Selecting previously unselected package po-debconf. 1568s Preparing to unpack .../44-po-debconf_1.0.21+nmu1_all.deb ... 1568s Unpacking po-debconf (1.0.21+nmu1) ... 1568s Selecting previously unselected package debhelper. 1568s Preparing to unpack .../45-debhelper_13.14.1ubuntu5_all.deb ... 1568s Unpacking debhelper (13.14.1ubuntu5) ... 1568s Selecting previously unselected package dh-apport. 1568s Preparing to unpack .../46-dh-apport_2.28.1-0ubuntu3_all.deb ... 1568s Unpacking dh-apport (2.28.1-0ubuntu3) ... 1568s Selecting previously unselected package golang-1.22-src. 1568s Preparing to unpack .../47-golang-1.22-src_1.22.2-2_all.deb ... 1568s Unpacking golang-1.22-src (1.22.2-2) ... 1570s Selecting previously unselected package golang-1.22-go. 1570s Preparing to unpack .../48-golang-1.22-go_1.22.2-2_amd64.deb ... 1570s Unpacking golang-1.22-go (1.22.2-2) ... 1570s Selecting previously unselected package golang-src. 1570s Preparing to unpack .../49-golang-src_2%3a1.22~2build1_all.deb ... 1570s Unpacking golang-src (2:1.22~2build1) ... 1570s Selecting previously unselected package golang-go:amd64. 1571s Preparing to unpack .../50-golang-go_2%3a1.22~2build1_amd64.deb ... 1571s Unpacking golang-go:amd64 (2:1.22~2build1) ... 1571s Selecting previously unselected package dh-golang. 1571s Preparing to unpack .../51-dh-golang_1.62_all.deb ... 1571s Unpacking dh-golang (1.62) ... 1571s Selecting previously unselected package autopkgtest-satdep. 1571s Preparing to unpack .../52-1-autopkgtest-satdep.deb ... 1571s Unpacking autopkgtest-satdep (0) ... 1571s Setting up libarchive-zip-perl (1.68-1) ... 1571s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 1571s Setting up m4 (1.4.19-4build1) ... 1571s Setting up libgomp1:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up dh-apport (2.28.1-0ubuntu3) ... 1571s Setting up autotools-dev (20220109.1) ... 1571s Setting up gcc-13-base:amd64 (13.2.0-23ubuntu4) ... 1571s Setting up golang-1.22-src (1.22.2-2) ... 1571s Setting up libquadmath0:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up libmpc3:amd64 (1.3.1-1build1) ... 1571s Setting up libatomic1:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up autopoint (0.21-14ubuntu2) ... 1571s Setting up autoconf (2.71-3) ... 1571s Setting up libubsan1:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up dwz (0.15-1build6) ... 1571s Setting up libhwasan0:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up libasan8:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up debugedit (1:5.0-5build2) ... 1571s Setting up libsub-override-perl (0.10-1) ... 1571s Setting up libtsan2:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up libisl23:amd64 (0.26-3build1) ... 1571s Setting up golang-src (2:1.22~2build1) ... 1571s Setting up libcc1-0:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up liblsan0:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up libitm1:amd64 (14-20240412-0ubuntu1) ... 1571s Setting up automake (1:1.16.5-1.3ubuntu1) ... 1571s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 1571s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 1571s Setting up gettext (0.21-14ubuntu2) ... 1571s Setting up cpp-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1571s Setting up golang-1.22-go (1.22.2-2) ... 1571s Setting up intltool-debian (0.35.0+20060710.6) ... 1571s Setting up dh-strip-nondeterminism (1.13.1-1) ... 1571s Setting up libgcc-13-dev:amd64 (13.2.0-23ubuntu4) ... 1571s Setting up libstdc++-13-dev:amd64 (13.2.0-23ubuntu4) ... 1571s Setting up cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1571s Setting up cpp-13 (13.2.0-23ubuntu4) ... 1571s Setting up golang-go:amd64 (2:1.22~2build1) ... 1571s Setting up gcc-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1571s Setting up po-debconf (1.0.21+nmu1) ... 1571s Setting up gcc-13 (13.2.0-23ubuntu4) ... 1571s Setting up cpp (4:13.2.0-7ubuntu1) ... 1571s Setting up g++-13-x86-64-linux-gnu (13.2.0-23ubuntu4) ... 1571s Setting up gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1571s Setting up libtool (2.4.7-7build1) ... 1571s Setting up gcc (4:13.2.0-7ubuntu1) ... 1571s Setting up dh-autoreconf (20) ... 1571s Setting up g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 1571s Setting up g++-13 (13.2.0-23ubuntu4) ... 1571s Setting up debhelper (13.14.1ubuntu5) ... 1571s Setting up g++ (4:13.2.0-7ubuntu1) ... 1571s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 1571s Setting up build-essential (12.10ubuntu1) ... 1571s Setting up dh-golang (1.62) ... 1571s Setting up autopkgtest-satdep (0) ... 1571s Processing triggers for man-db (2.12.0-4build2) ... 1572s Processing triggers for install-info (7.1-3build2) ... 1572s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 1574s (Reading database ... 90597 files and directories currently installed.) 1574s Removing autopkgtest-satdep (0) ... 1574s autopkgtest [03:07:02]: test command1: ./debian/tests/test 1574s autopkgtest [03:07:02]: test command1: [----------------------- 1608s === RUN TestRunSignal 1608s === RUN TestRunSignal/Send_SIGINT_exits 1608s INFO Starting WSL Pro Service version Dev 1608s === RUN TestRunSignal/Send_SIGTERM_exits 1608s INFO Starting WSL Pro Service version Dev 1608s --- PASS: TestRunSignal (0.20s) 1608s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 1608s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 1608s === RUN TestRun 1608s === PAUSE TestRun 1608s === CONT TestRun 1608s === RUN TestRun/Run_and_return_error 1608s === PAUSE TestRun/Run_and_return_error 1608s === RUN TestRun/Run_and_return_usage_error 1608s === PAUSE TestRun/Run_and_return_usage_error 1608s === RUN TestRun/Run_and_usage_error_only_does_not_fail 1608s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 1608s === RUN TestRun/Run_and_exit_successfully 1608s === PAUSE TestRun/Run_and_exit_successfully 1608s === CONT TestRun/Run_and_return_error 1608s === CONT TestRun/Run_and_usage_error_only_does_not_fail 1608s INFO Starting WSL Pro Service version Dev 1608s INFO Starting WSL Pro Service version Dev 1608s ERROR context.Background Error requested 1608s === CONT TestRun/Run_and_return_usage_error 1608s === CONT TestRun/Run_and_exit_successfully 1608s INFO Starting WSL Pro Service version Dev 1608s INFO Starting WSL Pro Service version Dev 1608s ERROR context.Background Error requested 1608s --- PASS: TestRun (0.00s) 1608s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 1608s --- PASS: TestRun/Run_and_return_error (0.10s) 1608s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 1608s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 1608s PASS 1608s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.408s 1608s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 1618s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 1620s === RUN TestHelp 1620s --- PASS: TestHelp (0.00s) 1620s === RUN TestCompletion 1620s --- PASS: TestCompletion (0.00s) 1620s === RUN TestVersion 1620s --- PASS: TestVersion (0.00s) 1620s === RUN TestNoUsageError 1620s --- PASS: TestNoUsageError (0.00s) 1620s === RUN TestUsageError 1620s === PAUSE TestUsageError 1620s === RUN TestCanQuitWhenExecute 1620s === PAUSE TestCanQuitWhenExecute 1620s === RUN TestCanQuitTwice 1620s === PAUSE TestCanQuitTwice 1620s === RUN TestAppCanQuitWithoutExecute 1620s === PAUSE TestAppCanQuitWithoutExecute 1620s === RUN TestAppRunFailsOnComponentsCreationAndQuit 1620s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 1620s === RUN TestAppGetRootCmd 1620s === PAUSE TestAppGetRootCmd 1620s === RUN TestWithProMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithProMock (0.00s) 1620s === RUN TestWithWslPathMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithWslPathMock (0.00s) 1620s === RUN TestWithWslInfoMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithWslInfoMock (0.00s) 1620s === RUN TestWithCmdExeMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithCmdExeMock (0.00s) 1620s === CONT TestUsageError 1620s === CONT TestAppCanQuitWithoutExecute 1620s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 1620s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 1620s === CONT TestAppGetRootCmd 1620s --- PASS: TestAppGetRootCmd (0.00s) 1620s === CONT TestAppRunFailsOnComponentsCreationAndQuit 1620s --- PASS: TestUsageError (0.00s) 1620s === CONT TestCanQuitTwice 1620s time="2024-05-24T03:07:36Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1620s time="2024-05-24T03:07:36Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:75 New() Building new daemon" 1620s time="2024-05-24T03:07:40Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1620s time="2024-05-24T03:07:40Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:40Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:40Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1620s time="2024-05-24T03:07:42Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:38441" 1620s time="2024-05-24T03:07:42Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1620s time="2024-05-24T03:07:42Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 1620s time="2024-05-24T03:07:42Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 1620s time="2024-05-24T03:07:43Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:43Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:43Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (6.79s) 1620s === CONT TestCanQuitWhenExecute 1620s time="2024-05-24T03:07:43Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38133" 1620s time="2024-05-24T03:07:43Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1620s time="2024-05-24T03:07:43Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:75 New() Building new daemon" 1620s time="2024-05-24T03:07:45Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/streams/server.go:141 (*Server).Serve() Server: sent preface messages to all streams" 1620s time="2024-05-24T03:07:45Z" level=warning msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:186 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 1620s time="2024-05-24T03:07:45Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1620s time="2024-05-24T03:07:45Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s time="2024-05-24T03:07:45Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:45Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:45Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s time="2024-05-24T03:07:45Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:45Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:45Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s time="2024-05-24T03:07:45Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.iliS7S/build.xCS/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1620s time="2024-05-24T03:07:45Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.iliS7S/build.xCS/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1620s time="2024-05-24T03:07:45Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.iliS7S/build.xCS/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1620s --- PASS: TestCanQuitTwice (8.89s) 1620s time="2024-05-24T03:07:46Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1620s time="2024-05-24T03:07:47Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:47Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:48Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:38133" 1620s time="2024-05-24T03:07:48Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1620s time="2024-05-24T03:07:48Z" level=warning msg="/tmp/autopkgtest.iliS7S/build.xCS/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:38133: connect: connection refused\"" 1620s time="2024-05-24T03:07:48Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1620s time="2024-05-24T03:07:48Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s time="2024-05-24T03:07:48Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1620s time="2024-05-24T03:07:48Z" level=info msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1620s time="2024-05-24T03:07:48Z" level=debug msg="/tmp/autopkgtest.iliS7S/build.xCS/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1620s --- PASS: TestCanQuitWhenExecute (4.96s) 1620s PASS 1620s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 11.777s 1620s === RUN TestApplyProToken 1620s === PAUSE TestApplyProToken 1620s === RUN TestApplyLandscapeConfig 1620s === PAUSE TestApplyLandscapeConfig 1620s === RUN TestWithProMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithProMock (0.00s) 1620s === RUN TestWithLandscapeConfigMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1620s === RUN TestWithWslPathMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithWslPathMock (0.00s) 1620s === RUN TestWithWslInfoMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithWslInfoMock (0.00s) 1620s === RUN TestWithCmdExeMock 1620s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1620s --- SKIP: TestWithCmdExeMock (0.00s) 1620s === CONT TestApplyProToken 1620s === RUN TestApplyProToken/Success_attaching 1620s === PAUSE TestApplyProToken/Success_attaching 1620s === RUN TestApplyProToken/Success_detaching 1620s === PAUSE TestApplyProToken/Success_detaching 1620s === RUN TestApplyProToken/Error_calling_pro_detach 1620s === PAUSE TestApplyProToken/Error_calling_pro_detach 1620s === RUN TestApplyProToken/Error_calling_pro_attach 1620s === PAUSE TestApplyProToken/Error_calling_pro_attach 1620s === CONT TestApplyProToken/Success_attaching 1620s === CONT TestApplyProToken/Error_calling_pro_attach 1620s time="2024-05-24T03:07:37Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1620s time="2024-05-24T03:07:37Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1620s === CONT TestApplyLandscapeConfig 1620s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 1620s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 1620s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 1620s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 1620s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 1620s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 1620s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 1620s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 1620s === CONT TestApplyProToken/Error_calling_pro_detach 1620s time="2024-05-24T03:07:40Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1620s === CONT TestApplyProToken/Success_detaching 1620s time="2024-05-24T03:07:41Z" level=info msg="ApplyProToken: Received empty token: detaching" 1620s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 1620s time="2024-05-24T03:07:42Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1620s --- PASS: TestApplyProToken (0.00s) 1620s --- PASS: TestApplyProToken/Success_attaching (3.24s) 1620s --- PASS: TestApplyProToken/Error_calling_pro_attach (3.79s) 1620s --- PASS: TestApplyProToken/Error_calling_pro_detach (1.80s) 1620s --- PASS: TestApplyProToken/Success_detaching (1.68s) 1620s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 1620s time="2024-05-24T03:07:43Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1620s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 1620s time="2024-05-24T03:07:44Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1620s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 1620s time="2024-05-24T03:07:44Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1620s --- PASS: TestApplyLandscapeConfig (0.00s) 1620s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (2.00s) 1620s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (1.82s) 1620s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (1.70s) 1620s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (1.53s) 1620s PASS 1620s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 8.828s 1686s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 1704s === RUN TestNew 1704s === PAUSE TestNew 1704s === RUN TestServe 1704s === PAUSE TestServe 1704s === RUN TestServeAndQuit 1704s === PAUSE TestServeAndQuit 1704s === RUN TestReconnection 1704s === PAUSE TestReconnection 1704s === RUN TestWithProMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithProMock (0.00s) 1704s === RUN TestWithWslPathMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslPathMock (0.00s) 1704s === RUN TestWithWslInfoMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslInfoMock (0.00s) 1704s === RUN TestWithCmdExeMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithCmdExeMock (0.00s) 1704s === CONT TestNew 1704s === RUN TestNew/Success 1704s === PAUSE TestNew/Success 1704s === RUN TestNew/Error_when_WslPath_returns_error 1704s === PAUSE TestNew/Error_when_WslPath_returns_error 1704s === CONT TestNew/Success 1704s === CONT TestServeAndQuit 1704s === RUN TestServeAndQuit/Success_with_graceful_quit 1704s === PAUSE TestServeAndQuit/Success_with_graceful_quit 1704s === RUN TestServeAndQuit/Success_with_forceful_quit 1704s === PAUSE TestServeAndQuit/Success_with_forceful_quit 1704s === RUN TestServeAndQuit/Success_with_double_quit 1704s === PAUSE TestServeAndQuit/Success_with_double_quit 1704s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 1704s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 1704s === CONT TestReconnection 1704s === RUN TestReconnection/Success_connecting_after_failing_to_connect 1704s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 1704s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 1704s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 1704s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1704s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1704s === CONT TestServe 1704s === RUN TestServe/No_connection_because_the_port_file_has_port_0 1704s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 1704s === RUN TestServe/No_connection_because_there_are_no_certificates 1704s === PAUSE TestServe/No_connection_because_there_are_no_certificates 1704s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1704s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1704s === RUN TestServe/Error_because_the_context_is_pre-cancelled 1704s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 1704s === RUN TestServe/Success 1704s === PAUSE TestServe/Success 1704s === RUN TestServe/Success_with_systemd_notifier_returning_true 1704s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 1704s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 1704s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 1704s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 1704s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 1704s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 1704s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 1704s === RUN TestServe/No_connection_because_the_port_file_is_empty 1704s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 1704s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 1704s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 1704s === RUN TestServe/No_connection_because_there_is_no_server 1704s === PAUSE TestServe/No_connection_because_there_is_no_server 1704s === RUN TestServe/Error_because_the_notifier_returns_an_error 1704s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 1704s === CONT TestNew/Error_when_WslPath_returns_error 1704s time="2024-05-24T03:07:47Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:07:47Z" level=debug msg="Building new daemon" 1704s === CONT TestServeAndQuit/Success_with_graceful_quit 1704s time="2024-05-24T03:07:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36665" 1704s time="2024-05-24T03:07:50Z" level=debug msg="Building new daemon" 1704s === CONT TestReconnection/Success_connecting_after_failing_to_connect 1704s --- PASS: TestNew (0.00s) 1704s --- PASS: TestNew/Success (2.78s) 1704s --- PASS: TestNew/Error_when_WslPath_returns_error (2.88s) 1704s time="2024-05-24T03:07:50Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:07:53Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:07:53Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:07:53Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:07:53Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:07:53Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:07:53Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:07:53Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_failing_to_connect756029017/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect756029017/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1704s time="2024-05-24T03:07:53Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:07:53Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:07:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40281" 1704s time="2024-05-24T03:07:54Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:07:54Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:07:55Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36665" 1704s time="2024-05-24T03:07:55Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:07:55Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:07:55Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:07:55Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:07:56Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40281" 1704s time="2024-05-24T03:07:56Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:07:56Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:07:56Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:07:56Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:07:56Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:07:56Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:07:56Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:07:56Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:07:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:07:56Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s === CONT TestServeAndQuit/Success_with_double_quit 1704s time="2024-05-24T03:07:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44251" 1704s time="2024-05-24T03:07:57Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:07:57Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:07:57Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:07:57Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:07:57Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:07:57Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:57Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:07:57Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:57Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:57Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:07:57Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:07:57Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:07:57Z" level=info msg="Stopping active requests." 1704s time="2024-05-24T03:07:57Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:07:57Z" level=debug msg="All connections have now ended." 1704s === CONT TestServeAndQuit/Success_with_forceful_quit 1704s time="2024-05-24T03:07:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37837" 1704s time="2024-05-24T03:07:57Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:07:58Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:07:58Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:07:58Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:07:59Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:07:59Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:07:59Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:07:59Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44251" 1704s time="2024-05-24T03:07:59Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:07:59Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:07:59Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:07:59Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:00Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37837" 1704s time="2024-05-24T03:08:00Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:00Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:00Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:00Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:00Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:00Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:00Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:08:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:08:00Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:08:00Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:00Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:00Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:01Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:01Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:01Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/No_connection_because_the_port_file_has_port_0 1704s time="2024-05-24T03:08:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34579" 1704s time="2024-05-24T03:08:01Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:01Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:01Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:01Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:01Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:01Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:01Z" level=info msg="Stopping active requests." 1704s time="2024-05-24T03:08:01Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:01Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:01Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:08:01Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:08:01Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:08:01Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:01Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:01Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1704s time="2024-05-24T03:08:01Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:03Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:03Z" 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" 1704s time="2024-05-24T03:08:03Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37987" 1704s time="2024-05-24T03:08:03Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:08:03Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:03Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:08:03Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37987" 1704s time="2024-05-24T03:08:03Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:03Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:08:03Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:03Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:04Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:04Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:04Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:04Z" 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" 1704s time="2024-05-24T03:08:04Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:08:04Z" level=debug msg="All connections have now ended." 1704s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 1704s time="2024-05-24T03:08:04Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:04Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:04Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:04Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:04Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:05Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34343" 1704s time="2024-05-24T03:08:05Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:08:05Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:05Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:08:06Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34343" 1704s time="2024-05-24T03:08:06Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:06Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:08:06Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:06Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:06Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:06Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:06Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:06Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:06Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:06Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:06Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:08:06Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:06Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:06Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34099" 1704s time="2024-05-24T03:08:07Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:07Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:08:08Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34099" 1704s time="2024-05-24T03:08:08Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:08Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:08:08Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:08Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:09Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:09Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:09Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:09Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:09Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:09Z" level=info msg="Stopping active requests." 1704s time="2024-05-24T03:08:09Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:09Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:09Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:09Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:08:09Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:08:09Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:08:09Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:08:09Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 1704s time="2024-05-24T03:08:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45405" 1704s time="2024-05-24T03:08:09Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:10Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:10Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:10Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:10Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:10Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s --- PASS: TestServeAndQuit (0.00s) 1704s --- PASS: TestServeAndQuit/Success_with_graceful_quit (6.87s) 1704s --- PASS: TestServeAndQuit/Success_with_double_quit (4.04s) 1704s --- PASS: TestServeAndQuit/Success_with_forceful_quit (3.97s) 1704s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (1.23s) 1704s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 1704s time="2024-05-24T03:08:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43269" 1704s time="2024-05-24T03:08:10Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:11Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:11Z" 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" 1704s time="2024-05-24T03:08:11Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:12Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:12Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:12Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/Error_because_the_notifier_returns_an_error 1704s time="2024-05-24T03:08:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38889" 1704s time="2024-05-24T03:08:12Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:14Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 1704s time="2024-05-24T03:08:14Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:14Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:14Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/No_connection_because_there_is_no_server 1704s time="2024-05-24T03:08:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40725" 1704s time="2024-05-24T03:08:14Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:15Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:15Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40725" 1704s time="2024-05-24T03:08:15Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:15Z" 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:40725: connect: connection refused\"" 1704s time="2024-05-24T03:08:15Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:16Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:16Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:16Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 1704s time="2024-05-24T03:08:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36353" 1704s time="2024-05-24T03:08:16Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:17Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:17Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:-5\": port cannot be negative" 1704s time="2024-05-24T03:08:17Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:18Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:18Z" 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" 1704s time="2024-05-24T03:08:18Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:08:18Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:18Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:18Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/No_connection_because_the_port_file_is_empty 1704s time="2024-05-24T03:08:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40563" 1704s time="2024-05-24T03:08:18Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:21Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:21Z" 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" 1704s time="2024-05-24T03:08:21Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:22Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:22Z" 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" 1704s time="2024-05-24T03:08:22Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:08:22Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:22Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:22Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 1704s time="2024-05-24T03:08:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38471" 1704s time="2024-05-24T03:08:22Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:25Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:27Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:27Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:27Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/Success 1704s time="2024-05-24T03:08:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41467" 1704s time="2024-05-24T03:08:27Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:29Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:31Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:41467" 1704s time="2024-05-24T03:08:31Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:31Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:31Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:32Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:32Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:32Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:32Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:33Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:33Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:33Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:33Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:33Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:08:33Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 1704s time="2024-05-24T03:08:33Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:33Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36049" 1704s time="2024-05-24T03:08:33Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:37Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:37Z" 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_exist1020324623/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist1020324623/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1704s time="2024-05-24T03:08:37Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:38Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:38Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:38Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/Success_with_systemd_notifier_returning_true 1704s time="2024-05-24T03:08:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33015" 1704s time="2024-05-24T03:08:38Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:41Z" level=debug msg="Ready state sent to systemd" 1704s time="2024-05-24T03:08:41Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:41Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:08:42Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33015" 1704s time="2024-05-24T03:08:42Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:08:42Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:08:42Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:08:42Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:08:43Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:08:43Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:08:43Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:08:43Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:08:45Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:45Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:45Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:08:45Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:45Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:08:45Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:08:45Z" level=debug msg="All connections have now ended." 1704s time="2024-05-24T03:08:45Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:45Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:45Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1704s time="2024-05-24T03:08:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35537" 1704s time="2024-05-24T03:08:45Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:48Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:49Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35537" 1704s time="2024-05-24T03:08:49Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file3048154095/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 1704s time="2024-05-24T03:08:49Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:50Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:50Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:50Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/Error_because_the_context_is_pre-cancelled 1704s time="2024-05-24T03:08:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44829" 1704s time="2024-05-24T03:08:50Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:53Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:53Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:53Z" level=debug msg="All connections have now ended." 1704s === CONT TestServe/No_connection_because_there_are_no_certificates 1704s time="2024-05-24T03:08:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38861" 1704s time="2024-05-24T03:08:53Z" level=debug msg="Building new daemon" 1704s time="2024-05-24T03:08:54Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:08:55Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38861" 1704s time="2024-05-24T03:08:55Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates2042561211/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 1704s time="2024-05-24T03:08:55Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:08:55Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:08:55Z" level=info msg="Waiting for active requests to close." 1704s time="2024-05-24T03:08:55Z" level=debug msg="All connections have now ended." 1704s --- PASS: TestServe (0.00s) 1704s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (2.97s) 1704s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (2.25s) 1704s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (1.30s) 1704s --- PASS: TestServe/No_connection_because_there_is_no_server (2.28s) 1704s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (2.25s) 1704s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (3.98s) 1704s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (4.56s) 1704s --- PASS: TestServe/Success (6.80s) 1704s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (4.33s) 1704s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (6.92s) 1704s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (4.93s) 1704s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (2.97s) 1704s --- PASS: TestServe/No_connection_because_there_are_no_certificates (2.74s) 1704s time="2024-05-24T03:09:09Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:09:09Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:09:09Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:09:09Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped258050060/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped258050060/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1704s time="2024-05-24T03:09:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1704s time="2024-05-24T03:09:09Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:09:09Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:09Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:09Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34159" 1704s time="2024-05-24T03:09:10Z" level=info msg="Daemon: connecting to Windows Agent" 1704s time="2024-05-24T03:09:10Z" level=debug msg="Updated systemd status to \"Connecting\"" 1704s time="2024-05-24T03:09:11Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34159" 1704s time="2024-05-24T03:09:11Z" level=info msg="Daemon: completed connection to Windows Agent" 1704s time="2024-05-24T03:09:11Z" level=debug msg="Updated systemd status to \"Connected\"" 1704s time="2024-05-24T03:09:11Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1704s time="2024-05-24T03:09:11Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1704s time="2024-05-24T03:09:12Z" level=debug msg="Server: sent preface messages to all streams" 1704s time="2024-05-24T03:09:12Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:09:12Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:09:12Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:09:12Z" level=info msg="Stopping daemon requested." 1704s time="2024-05-24T03:09:12Z" level=info msg="Stopping active requests." 1704s time="2024-05-24T03:09:12Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:12Z" level=warning msg="Daemon: disconnected from Windows host" 1704s time="2024-05-24T03:09:12Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1704s time="2024-05-24T03:09:12Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1704s time="2024-05-24T03:09:12Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:12Z" level=debug msg="Updated systemd status to \"Stopped\"" 1704s time="2024-05-24T03:09:12Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:09:12Z" level=debug msg="All connections have now ended." 1704s --- PASS: TestReconnection (0.00s) 1704s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (7.21s) 1704s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (5.29s) 1704s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (70.99s) 1704s PASS 1704s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 85.059s 1704s === RUN TestConnect 1704s === PAUSE TestConnect 1704s === RUN TestSendAndRecv 1704s --- PASS: TestSendAndRecv (0.41s) 1704s === RUN TestServe 1704s === PAUSE TestServe 1704s === RUN TestStop 1704s === PAUSE TestStop 1704s === RUN TestWithProMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithProMock (0.00s) 1704s === RUN TestWithWslPathMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslPathMock (0.00s) 1704s === RUN TestWithWslInfoMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslInfoMock (0.00s) 1704s === RUN TestWithCmdExeMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithCmdExeMock (0.00s) 1704s === CONT TestConnect 1704s === RUN TestConnect/Success 1704s === PAUSE TestConnect/Success 1704s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 1704s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 1704s === CONT TestStop 1704s === CONT TestServe 1704s time="2024-05-24T03:07:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46797" 1704s time="2024-05-24T03:07:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33733" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: Connected ready" 1704s time="2024-05-24T03:07:52Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:07:56Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s --- PASS: TestServe (6.84s) 1704s === CONT TestConnect/Success 1704s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 1704s time="2024-05-24T03:08:02Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:02Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1704s time="2024-05-24T03:08:02Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1704s --- PASS: TestStop (12.31s) 1704s --- PASS: TestConnect (0.00s) 1704s --- PASS: TestConnect/Success (0.30s) 1704s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.00s) 1704s PASS 1704s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 27.564s 1704s === RUN TestInfo 1704s === PAUSE TestInfo 1704s === RUN TestWslDistroName 1704s === PAUSE TestWslDistroName 1704s === RUN TestUserProfileDir 1704s === PAUSE TestUserProfileDir 1704s === RUN TestProStatus 1704s === PAUSE TestProStatus 1704s === RUN TestProAttach 1704s === PAUSE TestProAttach 1704s === RUN TestProDetach 1704s === PAUSE TestProDetach 1704s === RUN TestLandscapeEnable 1704s === PAUSE TestLandscapeEnable 1704s === RUN TestWindowsHostAddress 1704s === PAUSE TestWindowsHostAddress 1704s === RUN TestLandscapeDisable 1704s === PAUSE TestLandscapeDisable 1704s === RUN TestRealBackend 1704s === PAUSE TestRealBackend 1704s === RUN TestWithProMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithProMock (0.00s) 1704s === RUN TestWithLandscapeConfigMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1704s === RUN TestWithWslPathMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslPathMock (0.00s) 1704s === RUN TestWithWslInfoMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithWslInfoMock (0.00s) 1704s === RUN TestWithCmdExeMock 1704s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1704s --- SKIP: TestWithCmdExeMock (0.00s) 1704s === CONT TestInfo 1704s === RUN TestInfo/Error_when_pro_status_command_fails 1704s === PAUSE TestInfo/Error_when_pro_status_command_fails 1704s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 1704s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 1704s === CONT TestProDetach 1704s === RUN TestProDetach/success_on_unattached_distro 1704s === PAUSE TestProDetach/success_on_unattached_distro 1704s === RUN TestProDetach/success_on_attached_distro 1704s === PAUSE TestProDetach/success_on_attached_distro 1704s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1704s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1704s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 1704s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 1704s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 1704s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1704s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1704s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 1704s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 1704s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 1704s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1704s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1704s === CONT TestRealBackend 1704s === RUN TestInfo/Success 1704s === PAUSE TestInfo/Success 1704s === RUN TestInfo/Error_when_WslDistroName_fails 1704s === PAUSE TestInfo/Error_when_WslDistroName_fails 1704s === CONT TestLandscapeDisable 1704s === RUN TestLandscapeDisable/Success 1704s === PAUSE TestLandscapeDisable/Success 1704s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1704s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1704s === CONT TestWindowsHostAddress 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1704s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1704s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1704s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1704s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1704s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1704s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1704s === RUN TestWindowsHostAddress/Success_without_NAT 1704s === PAUSE TestWindowsHostAddress/Success_without_NAT 1704s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1704s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1704s === CONT TestLandscapeEnable 1704s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1704s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1704s === CONT TestProStatus 1704s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1704s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1704s === RUN TestProStatus/success_on_unattached_distro 1704s === PAUSE TestProStatus/success_on_unattached_distro 1704s === RUN TestProStatus/success_on_attached_distro 1704s === PAUSE TestProStatus/success_on_attached_distro 1704s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 1704s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 1704s === RUN TestProStatus/error_on_'pro_attach'_error 1704s === PAUSE TestProStatus/error_on_'pro_attach'_error 1704s === RUN TestLandscapeEnable/Success 1704s === PAUSE TestLandscapeEnable/Success 1704s === RUN TestLandscapeEnable/Success_overriding_computer_title 1704s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 1704s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1704s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1704s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1704s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1704s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1704s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1704s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1704s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1704s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1704s === CONT TestProAttach 1704s === RUN TestProAttach/success 1704s === PAUSE TestProAttach/success 1704s --- PASS: TestRealBackend (0.00s) 1704s === RUN TestProAttach/error_on_'pro_attach'_error 1704s === PAUSE TestProAttach/error_on_'pro_attach'_error 1704s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1704s === CONT TestUserProfileDir 1704s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 1704s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 1704s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1704s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1704s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1704s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1704s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1704s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1704s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1704s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1704s === CONT TestWslDistroName 1704s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1704s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1704s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1704s === RUN TestWslDistroName/Success_using_wslpath 1704s === PAUSE TestWslDistroName/Success_using_wslpath 1704s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1704s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1704s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1704s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1704s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1704s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1704s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1704s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1704s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1704s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1704s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1704s === CONT TestProDetach/success_on_unattached_distro 1704s === RUN TestUserProfileDir/Error_on_cmd.exe_error 1704s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 1704s === RUN TestUserProfileDir/Error_on_wslpath_error 1704s === PAUSE TestUserProfileDir/Error_on_wslpath_error 1704s === CONT TestInfo/Error_when_pro_status_command_fails 1704s === CONT TestLandscapeDisable/Success 1704s === CONT TestInfo/Error_when_WslDistroName_fails 1704s === CONT TestInfo/Success 1704s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 1704s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1704s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 1704s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1704s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1704s --- PASS: TestInfo (0.00s) 1704s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.41s) 1704s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.48s) 1704s --- PASS: TestInfo/Success (1.07s) 1704s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.03s) 1704s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.22s) 1704s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.32s) 1704s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.38s) 1704s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1704s --- PASS: TestLandscapeDisable (0.00s) 1704s --- PASS: TestLandscapeDisable/Success (1.40s) 1704s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.36s) 1704s === CONT TestWindowsHostAddress/Success_without_NAT 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1704s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1704s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1704s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1704s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 1704s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1704s --- PASS: TestWindowsHostAddress (0.00s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.42s) 1704s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (1.34s) 1704s --- PASS: TestWindowsHostAddress/Success_without_NAT (1.31s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.43s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.49s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (1.23s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (1.47s) 1704s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (1.50s) 1704s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (1.27s) 1704s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.38s) 1704s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1704s === CONT TestProDetach/success_on_attached_distro 1704s === CONT TestProStatus/success_on_unattached_distro 1704s --- PASS: TestProDetach (0.00s) 1704s --- PASS: TestProDetach/success_on_unattached_distro (1.42s) 1704s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.32s) 1704s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.35s) 1704s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (0.93s) 1704s --- PASS: TestProDetach/success_on_attached_distro (1.00s) 1704s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 1704s === CONT TestProStatus/error_on_'pro_attach'_error 1704s === CONT TestProStatus/success_on_attached_distro 1704s === CONT TestProAttach/success 1704s --- PASS: TestProStatus (0.00s) 1704s --- PASS: TestProStatus/success_on_unattached_distro (1.43s) 1704s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.47s) 1704s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.59s) 1704s --- PASS: TestProStatus/success_on_attached_distro (1.56s) 1704s === CONT TestProAttach/error_on_'pro_attach'_error 1704s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1704s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1704s --- PASS: TestProAttach (0.00s) 1704s --- PASS: TestProAttach/success (1.12s) 1704s --- PASS: TestProAttach/error_on_'pro_attach'_error (0.89s) 1704s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1704s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1704s time="2024-05-24T03:08:38Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1704s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1704s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1704s === CONT TestLandscapeEnable/Success_overriding_computer_title 1704s time="2024-05-24T03:08:39Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 1704s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 1704s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1704s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1704s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 1704s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1704s time="2024-05-24T03:08:42Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1704s === CONT TestLandscapeEnable/Success 1704s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1704s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 1704s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1704s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1704s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1704s === NAME TestLandscapeEnable/Success 1704s system_test.go:456: testdata/TestLandscapeEnable/golden/success 1704s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 1704s --- PASS: TestLandscapeEnable (0.00s) 1704s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 1704s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 1704s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (1.29s) 1704s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.38s) 1704s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (1.54s) 1704s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (2.75s) 1704s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (1.45s) 1704s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (1.48s) 1704s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.00s) 1704s --- PASS: TestLandscapeEnable/Success (1.34s) 1704s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1704s === CONT TestWslDistroName/Success_using_wslpath 1704s === CONT TestUserProfileDir/Error_on_wslpath_error 1704s --- PASS: TestWslDistroName (0.00s) 1704s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 1704s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.07s) 1704s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.31s) 1704s --- PASS: TestWslDistroName/Success_using_wslpath (1.40s) 1704s === CONT TestUserProfileDir/Error_on_cmd.exe_error 1704s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1704s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1704s system_test.go:205: Removing default proc/mounts 1704s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1704s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1704s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1704s system_test.go:205: Removing default proc/mounts 1704s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1704s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1704s --- PASS: TestUserProfileDir (0.00s) 1704s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.24s) 1704s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.72s) 1704s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 1704s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 1704s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.40s) 1704s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.47s) 1704s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 1704s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (2.62s) 1704s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.76s) 1704s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (2.90s) 1704s PASS 1704s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 35.901s 1704s autopkgtest [03:09:12]: test command1: -----------------------] 1705s autopkgtest [03:09:13]: test command1: - - - - - - - - - - results - - - - - - - - - - 1705s command1 PASS 1705s autopkgtest [03:09:13]: @@@@@@@@@@@@@@@@@@@@ summary 1705s command1 PASS 1715s Creating nova instance adt-noble-amd64-wsl-pro-service-20240524-020533-juju-7f2275-prod-proposed-migration-environment-2-4f354c29-86cb-4770-ae0a-b86c6bc436e6 from image adt/ubuntu-noble-amd64-server-20240523.img (UUID 87c650cc-f2ca-4672-a6c5-253cddf38e12)...