0s autopkgtest [17:33:24]: starting date and time: 2025-03-15 17:33:24+0000 0s autopkgtest [17:33:24]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [17:33:24]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.wcvm69ln/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-18.secgroup --name adt-plucky-arm64-wsl-pro-service-20250315-173324-juju-7f2275-prod-proposed-migration-environment-2-a4f6494f-67f3-4c36-868c-14327e314d96 --image adt/ubuntu-plucky-arm64-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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 170s autopkgtest [17:36:14]: testbed dpkg architecture: arm64 170s autopkgtest [17:36:14]: testbed apt version: 2.9.33 171s autopkgtest [17:36:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 171s autopkgtest [17:36:15]: testbed release detected to be: None 172s autopkgtest [17:36:16]: updating testbed package index (apt update) 172s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 172s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 172s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 172s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 173s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 173s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 173s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 173s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [111 kB] 173s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 c-n-f Metadata [1856 B] 173s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 c-n-f Metadata [116 B] 173s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [324 kB] 174s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 c-n-f Metadata [14.7 kB] 174s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [4948 B] 174s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 c-n-f Metadata [268 B] 174s Fetched 1078 kB in 2s (641 kB/s) 175s Reading package lists... 175s Reading package lists... 176s Building dependency tree... 176s Reading state information... 176s Calculating upgrade... 176s Calculating upgrade... 177s The following packages will be upgraded: 177s pinentry-curses python3-jinja2 strace 177s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 177s Need to get 647 kB of archives. 177s After this operation, 11.3 kB of additional disk space will be used. 177s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 strace arm64 6.13+ds-1ubuntu1 [499 kB] 177s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 pinentry-curses arm64 1.3.1-2ubuntu3 [39.2 kB] 177s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 178s Fetched 647 kB in 1s (612 kB/s) 178s (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 ... 117701 files and directories currently installed.) 178s Preparing to unpack .../strace_6.13+ds-1ubuntu1_arm64.deb ... 178s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 178s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_arm64.deb ... 178s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 178s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 179s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 179s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 179s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 179s Setting up strace (6.13+ds-1ubuntu1) ... 179s Processing triggers for man-db (2.13.0-1) ... 180s Reading package lists... 180s Building dependency tree... 180s Reading state information... 180s Solving dependencies... 181s The following packages will be REMOVED: 181s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 181s libunwind8* linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 181s linux-image-6.11.0-8-generic* linux-modules-6.11.0-8-generic* 181s linux-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 181s 0 upgraded, 0 newly installed, 11 to remove and 5 not upgraded. 181s After this operation, 267 MB disk space will be freed. 181s (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 ... 117701 files and directories currently installed.) 181s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 181s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 181s Removing libpython3.12t64:arm64 (3.12.9-1) ... 181s Removing libpython3.12-stdlib:arm64 (3.12.9-1) ... 181s Removing libnsl2:arm64 (1.3.0-3build3) ... 181s Removing libpython3.12-minimal:arm64 (3.12.9-1) ... 181s Removing libunwind8:arm64 (1.6.2-3.1) ... 181s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 182s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 183s Removing linux-image-6.11.0-8-generic (6.11.0-8.8) ... 183s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-10-generic 183s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-10-generic 183s /etc/kernel/postrm.d/initramfs-tools: 183s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 183s /etc/kernel/postrm.d/zz-flash-kernel: 183s flash-kernel: Kernel 6.11.0-8-generic has been removed. 183s flash-kernel: A higher version (6.14.0-10-generic) is still installed, no reflashing required. 183s /etc/kernel/postrm.d/zz-update-grub: 183s Sourcing file `/etc/default/grub' 183s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 184s Generating grub configuration file ... 184s Found linux image: /boot/vmlinuz-6.14.0-10-generic 184s Found initrd image: /boot/initrd.img-6.14.0-10-generic 184s Warning: os-prober will not be executed to detect other bootable partitions. 184s Systems on them will not be added to the GRUB boot configuration. 184s Check GRUB_DISABLE_OS_PROBER documentation entry. 184s Adding boot menu entry for UEFI Firmware Settings ... 184s done 184s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 184s Processing triggers for libc-bin (2.41-1ubuntu1) ... 184s (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 ... 81650 files and directories currently installed.) 184s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8) ... 184s Purging configuration files for libpython3.12-minimal:arm64 (3.12.9-1) ... 184s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 185s autopkgtest [17:36:29]: upgrading testbed (apt dist-upgrade and autopurge) 185s Reading package lists... 185s Building dependency tree... 185s Reading state information... 186s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 186s Starting 2 pkgProblemResolver with broken count: 0 186s Done 186s Entering ResolveByKeep 187s 187s Calculating upgrade... 187s The following packages will be upgraded: 187s libc-bin libc-dev-bin libc6 libc6-dev locales 187s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 187s Need to get 9530 kB of archives. 187s After this operation, 0 B of additional disk space will be used. 187s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6-dev arm64 2.41-1ubuntu2 [1750 kB] 189s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-dev-bin arm64 2.41-1ubuntu2 [24.0 kB] 189s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6 arm64 2.41-1ubuntu2 [2910 kB] 192s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-bin arm64 2.41-1ubuntu2 [600 kB] 193s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 locales all 2.41-1ubuntu2 [4246 kB] 197s Preconfiguring packages ... 197s Fetched 9530 kB in 10s (997 kB/s) 197s (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 ... 81647 files and directories currently installed.) 197s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_arm64.deb ... 197s Unpacking libc6-dev:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 198s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_arm64.deb ... 198s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 198s Preparing to unpack .../libc6_2.41-1ubuntu2_arm64.deb ... 198s Unpacking libc6:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 198s Setting up libc6:arm64 (2.41-1ubuntu2) ... 198s (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 ... 81647 files and directories currently installed.) 198s Preparing to unpack .../libc-bin_2.41-1ubuntu2_arm64.deb ... 198s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 198s Setting up libc-bin (2.41-1ubuntu2) ... 199s (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 ... 81647 files and directories currently installed.) 199s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 199s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 199s Setting up locales (2.41-1ubuntu2) ... 199s Generating locales (this might take a while)... 201s en_US.UTF-8... done 201s Generation complete. 201s Setting up libc-dev-bin (2.41-1ubuntu2) ... 201s Setting up libc6-dev:arm64 (2.41-1ubuntu2) ... 201s Processing triggers for man-db (2.13.0-1) ... 202s Processing triggers for systemd (257.3-1ubuntu3) ... 203s Reading package lists... 203s Building dependency tree... 203s Reading state information... 204s Starting pkgProblemResolver with broken count: 0 204s Starting 2 pkgProblemResolver with broken count: 0 204s Done 204s Solving dependencies... 205s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 205s autopkgtest [17:36:49]: rebooting testbed after setup commands that affected boot 228s autopkgtest [17:37:12]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP PREEMPT_DYNAMIC Wed Mar 12 15:45:31 UTC 2025 231s autopkgtest [17:37:15]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 235s Get:1 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (dsc) [1422 B] 235s Get:2 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (tar) [1790 kB] 235s gpgv: Signature made Tue Sep 10 12:05:15 2024 UTC 235s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 235s gpgv: Can't check signature: No public key 235s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.5.dsc: no acceptable signature found 236s autopkgtest [17:37:20]: testing package wsl-pro-service version 0.1.5 236s autopkgtest [17:37:20]: build not needed 237s autopkgtest [17:37:21]: test command1: preparing testbed 238s Reading package lists... 238s Building dependency tree... 238s Reading state information... 238s Starting pkgProblemResolver with broken count: 0 238s Starting 2 pkgProblemResolver with broken count: 0 238s Done 239s The following NEW packages will be installed: 239s autoconf automake autopoint autotools-dev binutils-gold 239s binutils-gold-aarch64-linux-gnu build-essential cpp cpp-14 239s cpp-14-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper debugedit dh-apport 239s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-14 239s g++-14-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-14 239s gcc-14-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext golang-1.24-go 239s golang-1.24-src golang-go golang-src intltool-debian libarchive-zip-perl 239s libasan8 libcc1-0 libdebhelper-perl libfile-stripnondeterminism-perl 239s libgcc-14-dev libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 239s libstdc++-14-dev libtool libtsan2 libubsan1 m4 po-debconf 239s 0 upgraded, 50 newly installed, 0 to remove and 0 not upgraded. 239s Need to get 115 MB of archives. 239s After this operation, 483 MB of additional disk space will be used. 239s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 m4 arm64 1.4.19-7 [244 kB] 240s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 autoconf all 2.72-3ubuntu1 [383 kB] 240s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 autotools-dev all 20220109.1 [44.9 kB] 240s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 automake all 1:1.17-3ubuntu1 [572 kB] 241s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 autopoint all 0.23.1-1 [619 kB] 241s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 binutils-gold-aarch64-linux-gnu arm64 2.44-1 [2034 kB] 243s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 binutils-gold arm64 2.44-1 [2162 B] 243s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 libisl23 arm64 0.27-1 [676 kB] 244s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 libmpc3 arm64 1.3.1-1build2 [56.8 kB] 244s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 cpp-14-aarch64-linux-gnu arm64 14.2.0-17ubuntu3 [10.6 MB] 254s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 cpp-14 arm64 14.2.0-17ubuntu3 [1028 B] 254s Get:12 http://ftpmaster.internal/ubuntu plucky/main arm64 cpp-aarch64-linux-gnu arm64 4:14.2.0-1ubuntu1 [5558 B] 254s Get:13 http://ftpmaster.internal/ubuntu plucky/main arm64 cpp arm64 4:14.2.0-1ubuntu1 [22.4 kB] 254s Get:14 http://ftpmaster.internal/ubuntu plucky/main arm64 libcc1-0 arm64 15-20250222-0ubuntu1 [44.2 kB] 254s Get:15 http://ftpmaster.internal/ubuntu plucky/main arm64 libgomp1 arm64 15-20250222-0ubuntu1 [146 kB] 254s Get:16 http://ftpmaster.internal/ubuntu plucky/main arm64 libitm1 arm64 15-20250222-0ubuntu1 [28.0 kB] 254s Get:17 http://ftpmaster.internal/ubuntu plucky/main arm64 libasan8 arm64 15-20250222-0ubuntu1 [2924 kB] 257s Get:18 http://ftpmaster.internal/ubuntu plucky/main arm64 liblsan0 arm64 15-20250222-0ubuntu1 [1319 kB] 258s Get:19 http://ftpmaster.internal/ubuntu plucky/main arm64 libtsan2 arm64 15-20250222-0ubuntu1 [2694 kB] 261s Get:20 http://ftpmaster.internal/ubuntu plucky/main arm64 libubsan1 arm64 15-20250222-0ubuntu1 [1178 kB] 262s Get:21 http://ftpmaster.internal/ubuntu plucky/main arm64 libhwasan0 arm64 15-20250222-0ubuntu1 [1642 kB] 264s Get:22 http://ftpmaster.internal/ubuntu plucky/main arm64 libgcc-14-dev arm64 14.2.0-17ubuntu3 [2593 kB] 266s Get:23 http://ftpmaster.internal/ubuntu plucky/main arm64 gcc-14-aarch64-linux-gnu arm64 14.2.0-17ubuntu3 [20.9 MB] 287s Get:24 http://ftpmaster.internal/ubuntu plucky/main arm64 gcc-14 arm64 14.2.0-17ubuntu3 [526 kB] 288s Get:25 http://ftpmaster.internal/ubuntu plucky/main arm64 gcc-aarch64-linux-gnu arm64 4:14.2.0-1ubuntu1 [1200 B] 288s Get:26 http://ftpmaster.internal/ubuntu plucky/main arm64 gcc arm64 4:14.2.0-1ubuntu1 [4998 B] 288s Get:27 http://ftpmaster.internal/ubuntu plucky/main arm64 libstdc++-14-dev arm64 14.2.0-17ubuntu3 [2499 kB] 290s Get:28 http://ftpmaster.internal/ubuntu plucky/main arm64 g++-14-aarch64-linux-gnu arm64 14.2.0-17ubuntu3 [12.1 MB] 302s Get:29 http://ftpmaster.internal/ubuntu plucky/main arm64 g++-14 arm64 14.2.0-17ubuntu3 [21.8 kB] 302s Get:30 http://ftpmaster.internal/ubuntu plucky/main arm64 g++-aarch64-linux-gnu arm64 4:14.2.0-1ubuntu1 [956 B] 302s Get:31 http://ftpmaster.internal/ubuntu plucky/main arm64 g++ arm64 4:14.2.0-1ubuntu1 [1080 B] 302s Get:32 http://ftpmaster.internal/ubuntu plucky/main arm64 build-essential arm64 12.10ubuntu1 [4932 B] 302s Get:33 http://ftpmaster.internal/ubuntu plucky/main arm64 libdebhelper-perl all 13.24.1ubuntu2 [95.4 kB] 302s Get:34 http://ftpmaster.internal/ubuntu plucky/main arm64 libtool all 2.5.4-4 [168 kB] 302s Get:35 http://ftpmaster.internal/ubuntu plucky/main arm64 dh-autoreconf all 20 [16.1 kB] 302s Get:36 http://ftpmaster.internal/ubuntu plucky/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 303s Get:37 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-stripnondeterminism-perl all 1.14.1-2 [20.3 kB] 303s Get:38 http://ftpmaster.internal/ubuntu plucky/main arm64 dh-strip-nondeterminism all 1.14.1-2 [5064 B] 303s Get:39 http://ftpmaster.internal/ubuntu plucky/main arm64 debugedit arm64 1:5.1-2 [46.1 kB] 303s Get:40 http://ftpmaster.internal/ubuntu plucky/main arm64 dwz arm64 0.15-1build6 [113 kB] 303s Get:41 http://ftpmaster.internal/ubuntu plucky/main arm64 gettext arm64 0.23.1-1 [998 kB] 304s Get:42 http://ftpmaster.internal/ubuntu plucky/main arm64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 304s Get:43 http://ftpmaster.internal/ubuntu plucky/main arm64 po-debconf all 1.0.21+nmu1 [233 kB] 304s Get:44 http://ftpmaster.internal/ubuntu plucky/main arm64 debhelper all 13.24.1ubuntu2 [895 kB] 305s Get:45 http://ftpmaster.internal/ubuntu plucky/universe arm64 dh-apport all 2.32.0-0ubuntu2 [19.3 kB] 305s Get:46 http://ftpmaster.internal/ubuntu plucky/main arm64 golang-1.24-src all 1.24.1-1 [22.0 MB] 325s Get:47 http://ftpmaster.internal/ubuntu plucky/main arm64 golang-1.24-go arm64 1.24.1-1 [26.5 MB] 348s Get:48 http://ftpmaster.internal/ubuntu plucky/main arm64 golang-src all 2:1.24~2 [5136 B] 348s Get:49 http://ftpmaster.internal/ubuntu plucky/main arm64 golang-go arm64 2:1.24~2 [44.0 kB] 348s Get:50 http://ftpmaster.internal/ubuntu plucky/main arm64 dh-golang all 1.63 [24.9 kB] 348s Fetched 115 MB in 1min 49s (1061 kB/s) 348s Selecting previously unselected package m4. 349s (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 ... 81647 files and directories currently installed.) 349s Preparing to unpack .../00-m4_1.4.19-7_arm64.deb ... 349s Unpacking m4 (1.4.19-7) ... 349s Selecting previously unselected package autoconf. 349s Preparing to unpack .../01-autoconf_2.72-3ubuntu1_all.deb ... 349s Unpacking autoconf (2.72-3ubuntu1) ... 349s Selecting previously unselected package autotools-dev. 349s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 349s Unpacking autotools-dev (20220109.1) ... 349s Selecting previously unselected package automake. 349s Preparing to unpack .../03-automake_1%3a1.17-3ubuntu1_all.deb ... 349s Unpacking automake (1:1.17-3ubuntu1) ... 349s Selecting previously unselected package autopoint. 349s Preparing to unpack .../04-autopoint_0.23.1-1_all.deb ... 349s Unpacking autopoint (0.23.1-1) ... 349s Selecting previously unselected package binutils-gold-aarch64-linux-gnu. 349s Preparing to unpack .../05-binutils-gold-aarch64-linux-gnu_2.44-1_arm64.deb ... 349s Unpacking binutils-gold-aarch64-linux-gnu (2.44-1) ... 349s Selecting previously unselected package binutils-gold. 349s Preparing to unpack .../06-binutils-gold_2.44-1_arm64.deb ... 349s Unpacking binutils-gold (2.44-1) ... 349s Selecting previously unselected package libisl23:arm64. 349s Preparing to unpack .../07-libisl23_0.27-1_arm64.deb ... 349s Unpacking libisl23:arm64 (0.27-1) ... 349s Selecting previously unselected package libmpc3:arm64. 349s Preparing to unpack .../08-libmpc3_1.3.1-1build2_arm64.deb ... 349s Unpacking libmpc3:arm64 (1.3.1-1build2) ... 349s Selecting previously unselected package cpp-14-aarch64-linux-gnu. 349s Preparing to unpack .../09-cpp-14-aarch64-linux-gnu_14.2.0-17ubuntu3_arm64.deb ... 349s Unpacking cpp-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 349s Selecting previously unselected package cpp-14. 349s Preparing to unpack .../10-cpp-14_14.2.0-17ubuntu3_arm64.deb ... 349s Unpacking cpp-14 (14.2.0-17ubuntu3) ... 349s Selecting previously unselected package cpp-aarch64-linux-gnu. 349s Preparing to unpack .../11-cpp-aarch64-linux-gnu_4%3a14.2.0-1ubuntu1_arm64.deb ... 349s Unpacking cpp-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 349s Selecting previously unselected package cpp. 349s Preparing to unpack .../12-cpp_4%3a14.2.0-1ubuntu1_arm64.deb ... 349s Unpacking cpp (4:14.2.0-1ubuntu1) ... 349s Selecting previously unselected package libcc1-0:arm64. 349s Preparing to unpack .../13-libcc1-0_15-20250222-0ubuntu1_arm64.deb ... 349s Unpacking libcc1-0:arm64 (15-20250222-0ubuntu1) ... 349s Selecting previously unselected package libgomp1:arm64. 349s Preparing to unpack .../14-libgomp1_15-20250222-0ubuntu1_arm64.deb ... 349s Unpacking libgomp1:arm64 (15-20250222-0ubuntu1) ... 349s Selecting previously unselected package libitm1:arm64. 349s Preparing to unpack .../15-libitm1_15-20250222-0ubuntu1_arm64.deb ... 349s Unpacking libitm1:arm64 (15-20250222-0ubuntu1) ... 349s Selecting previously unselected package libasan8:arm64. 349s Preparing to unpack .../16-libasan8_15-20250222-0ubuntu1_arm64.deb ... 349s Unpacking libasan8:arm64 (15-20250222-0ubuntu1) ... 350s Selecting previously unselected package liblsan0:arm64. 350s Preparing to unpack .../17-liblsan0_15-20250222-0ubuntu1_arm64.deb ... 350s Unpacking liblsan0:arm64 (15-20250222-0ubuntu1) ... 350s Selecting previously unselected package libtsan2:arm64. 350s Preparing to unpack .../18-libtsan2_15-20250222-0ubuntu1_arm64.deb ... 350s Unpacking libtsan2:arm64 (15-20250222-0ubuntu1) ... 350s Selecting previously unselected package libubsan1:arm64. 350s Preparing to unpack .../19-libubsan1_15-20250222-0ubuntu1_arm64.deb ... 350s Unpacking libubsan1:arm64 (15-20250222-0ubuntu1) ... 350s Selecting previously unselected package libhwasan0:arm64. 350s Preparing to unpack .../20-libhwasan0_15-20250222-0ubuntu1_arm64.deb ... 350s Unpacking libhwasan0:arm64 (15-20250222-0ubuntu1) ... 350s Selecting previously unselected package libgcc-14-dev:arm64. 350s Preparing to unpack .../21-libgcc-14-dev_14.2.0-17ubuntu3_arm64.deb ... 350s Unpacking libgcc-14-dev:arm64 (14.2.0-17ubuntu3) ... 350s Selecting previously unselected package gcc-14-aarch64-linux-gnu. 350s Preparing to unpack .../22-gcc-14-aarch64-linux-gnu_14.2.0-17ubuntu3_arm64.deb ... 350s Unpacking gcc-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 350s Selecting previously unselected package gcc-14. 350s Preparing to unpack .../23-gcc-14_14.2.0-17ubuntu3_arm64.deb ... 350s Unpacking gcc-14 (14.2.0-17ubuntu3) ... 350s Selecting previously unselected package gcc-aarch64-linux-gnu. 350s Preparing to unpack .../24-gcc-aarch64-linux-gnu_4%3a14.2.0-1ubuntu1_arm64.deb ... 350s Unpacking gcc-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 350s Selecting previously unselected package gcc. 350s Preparing to unpack .../25-gcc_4%3a14.2.0-1ubuntu1_arm64.deb ... 350s Unpacking gcc (4:14.2.0-1ubuntu1) ... 350s Selecting previously unselected package libstdc++-14-dev:arm64. 350s Preparing to unpack .../26-libstdc++-14-dev_14.2.0-17ubuntu3_arm64.deb ... 350s Unpacking libstdc++-14-dev:arm64 (14.2.0-17ubuntu3) ... 351s Selecting previously unselected package g++-14-aarch64-linux-gnu. 351s Preparing to unpack .../27-g++-14-aarch64-linux-gnu_14.2.0-17ubuntu3_arm64.deb ... 351s Unpacking g++-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 351s Selecting previously unselected package g++-14. 351s Preparing to unpack .../28-g++-14_14.2.0-17ubuntu3_arm64.deb ... 351s Unpacking g++-14 (14.2.0-17ubuntu3) ... 351s Selecting previously unselected package g++-aarch64-linux-gnu. 351s Preparing to unpack .../29-g++-aarch64-linux-gnu_4%3a14.2.0-1ubuntu1_arm64.deb ... 351s Unpacking g++-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 351s Selecting previously unselected package g++. 351s Preparing to unpack .../30-g++_4%3a14.2.0-1ubuntu1_arm64.deb ... 351s Unpacking g++ (4:14.2.0-1ubuntu1) ... 351s Selecting previously unselected package build-essential. 351s Preparing to unpack .../31-build-essential_12.10ubuntu1_arm64.deb ... 351s Unpacking build-essential (12.10ubuntu1) ... 351s Selecting previously unselected package libdebhelper-perl. 351s Preparing to unpack .../32-libdebhelper-perl_13.24.1ubuntu2_all.deb ... 351s Unpacking libdebhelper-perl (13.24.1ubuntu2) ... 351s Selecting previously unselected package libtool. 351s Preparing to unpack .../33-libtool_2.5.4-4_all.deb ... 351s Unpacking libtool (2.5.4-4) ... 351s Selecting previously unselected package dh-autoreconf. 351s Preparing to unpack .../34-dh-autoreconf_20_all.deb ... 351s Unpacking dh-autoreconf (20) ... 351s Selecting previously unselected package libarchive-zip-perl. 351s Preparing to unpack .../35-libarchive-zip-perl_1.68-1_all.deb ... 351s Unpacking libarchive-zip-perl (1.68-1) ... 351s Selecting previously unselected package libfile-stripnondeterminism-perl. 351s Preparing to unpack .../36-libfile-stripnondeterminism-perl_1.14.1-2_all.deb ... 351s Unpacking libfile-stripnondeterminism-perl (1.14.1-2) ... 351s Selecting previously unselected package dh-strip-nondeterminism. 351s Preparing to unpack .../37-dh-strip-nondeterminism_1.14.1-2_all.deb ... 351s Unpacking dh-strip-nondeterminism (1.14.1-2) ... 351s Selecting previously unselected package debugedit. 351s Preparing to unpack .../38-debugedit_1%3a5.1-2_arm64.deb ... 351s Unpacking debugedit (1:5.1-2) ... 351s Selecting previously unselected package dwz. 351s Preparing to unpack .../39-dwz_0.15-1build6_arm64.deb ... 351s Unpacking dwz (0.15-1build6) ... 351s Selecting previously unselected package gettext. 351s Preparing to unpack .../40-gettext_0.23.1-1_arm64.deb ... 351s Unpacking gettext (0.23.1-1) ... 351s Selecting previously unselected package intltool-debian. 351s Preparing to unpack .../41-intltool-debian_0.35.0+20060710.6_all.deb ... 351s Unpacking intltool-debian (0.35.0+20060710.6) ... 351s Selecting previously unselected package po-debconf. 351s Preparing to unpack .../42-po-debconf_1.0.21+nmu1_all.deb ... 351s Unpacking po-debconf (1.0.21+nmu1) ... 351s Selecting previously unselected package debhelper. 351s Preparing to unpack .../43-debhelper_13.24.1ubuntu2_all.deb ... 351s Unpacking debhelper (13.24.1ubuntu2) ... 351s Selecting previously unselected package dh-apport. 351s Preparing to unpack .../44-dh-apport_2.32.0-0ubuntu2_all.deb ... 351s Unpacking dh-apport (2.32.0-0ubuntu2) ... 351s Selecting previously unselected package golang-1.24-src. 351s Preparing to unpack .../45-golang-1.24-src_1.24.1-1_all.deb ... 351s Unpacking golang-1.24-src (1.24.1-1) ... 353s Selecting previously unselected package golang-1.24-go. 353s Preparing to unpack .../46-golang-1.24-go_1.24.1-1_arm64.deb ... 353s Unpacking golang-1.24-go (1.24.1-1) ... 354s Selecting previously unselected package golang-src. 354s Preparing to unpack .../47-golang-src_2%3a1.24~2_all.deb ... 354s Unpacking golang-src (2:1.24~2) ... 354s Selecting previously unselected package golang-go:arm64. 354s Preparing to unpack .../48-golang-go_2%3a1.24~2_arm64.deb ... 354s Unpacking golang-go:arm64 (2:1.24~2) ... 354s Selecting previously unselected package dh-golang. 354s Preparing to unpack .../49-dh-golang_1.63_all.deb ... 354s Unpacking dh-golang (1.63) ... 354s Setting up golang-1.24-src (1.24.1-1) ... 354s Setting up libarchive-zip-perl (1.68-1) ... 354s Setting up libdebhelper-perl (13.24.1ubuntu2) ... 354s Setting up m4 (1.4.19-7) ... 354s Setting up binutils-gold-aarch64-linux-gnu (2.44-1) ... 354s Setting up libgomp1:arm64 (15-20250222-0ubuntu1) ... 354s Setting up dh-apport (2.32.0-0ubuntu2) ... 354s Setting up autotools-dev (20220109.1) ... 354s Setting up libmpc3:arm64 (1.3.1-1build2) ... 354s Setting up autopoint (0.23.1-1) ... 354s Setting up autoconf (2.72-3ubuntu1) ... 354s Setting up libubsan1:arm64 (15-20250222-0ubuntu1) ... 354s Setting up dwz (0.15-1build6) ... 354s Setting up libhwasan0:arm64 (15-20250222-0ubuntu1) ... 354s Setting up libasan8:arm64 (15-20250222-0ubuntu1) ... 354s Setting up debugedit (1:5.1-2) ... 354s Setting up libtsan2:arm64 (15-20250222-0ubuntu1) ... 354s Setting up libisl23:arm64 (0.27-1) ... 354s Setting up golang-src (2:1.24~2) ... 354s Setting up libcc1-0:arm64 (15-20250222-0ubuntu1) ... 354s Setting up liblsan0:arm64 (15-20250222-0ubuntu1) ... 354s Setting up libitm1:arm64 (15-20250222-0ubuntu1) ... 354s Setting up automake (1:1.17-3ubuntu1) ... 354s update-alternatives: using /usr/bin/automake-1.17 to provide /usr/bin/automake (automake) in auto mode 354s Setting up libfile-stripnondeterminism-perl (1.14.1-2) ... 354s Setting up gettext (0.23.1-1) ... 354s Setting up binutils-gold (2.44-1) ... 354s Setting up intltool-debian (0.35.0+20060710.6) ... 354s Setting up golang-1.24-go (1.24.1-1) ... 354s Setting up dh-strip-nondeterminism (1.14.1-2) ... 354s Setting up cpp-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 354s Setting up libgcc-14-dev:arm64 (14.2.0-17ubuntu3) ... 354s Setting up libstdc++-14-dev:arm64 (14.2.0-17ubuntu3) ... 354s Setting up golang-go:arm64 (2:1.24~2) ... 354s Setting up po-debconf (1.0.21+nmu1) ... 354s Setting up cpp-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 354s Setting up cpp-14 (14.2.0-17ubuntu3) ... 354s Setting up cpp (4:14.2.0-1ubuntu1) ... 354s Setting up gcc-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 354s Setting up gcc-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 354s Setting up g++-14-aarch64-linux-gnu (14.2.0-17ubuntu3) ... 354s Setting up gcc-14 (14.2.0-17ubuntu3) ... 354s Setting up g++-aarch64-linux-gnu (4:14.2.0-1ubuntu1) ... 354s Setting up g++-14 (14.2.0-17ubuntu3) ... 354s Setting up libtool (2.5.4-4) ... 354s Setting up gcc (4:14.2.0-1ubuntu1) ... 354s Setting up dh-autoreconf (20) ... 354s Setting up g++ (4:14.2.0-1ubuntu1) ... 354s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 354s Setting up build-essential (12.10ubuntu1) ... 354s Setting up debhelper (13.24.1ubuntu2) ... 354s Setting up dh-golang (1.63) ... 354s Processing triggers for man-db (2.13.0-1) ... 355s Processing triggers for install-info (7.1.1-1) ... 355s Processing triggers for libc-bin (2.41-1ubuntu2) ... 356s autopkgtest [17:39:20]: test command1: ./debian/tests/test 356s autopkgtest [17:39:20]: test command1: [----------------------- 397s === RUN TestRunSignal 397s === RUN TestRunSignal/Send_SIGINT_exits 397s INFO Starting WSL Pro Service version Dev 397s === RUN TestRunSignal/Send_SIGTERM_exits 397s INFO Starting WSL Pro Service version Dev 397s --- PASS: TestRunSignal (0.20s) 397s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 397s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 397s === RUN TestRun 397s === PAUSE TestRun 397s === CONT TestRun 397s === RUN TestRun/Run_and_exit_successfully 397s === PAUSE TestRun/Run_and_exit_successfully 397s === RUN TestRun/Run_and_return_error 397s === PAUSE TestRun/Run_and_return_error 397s === RUN TestRun/Run_and_return_usage_error 397s === PAUSE TestRun/Run_and_return_usage_error 397s === RUN TestRun/Run_and_usage_error_only_does_not_fail 397s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 397s === CONT TestRun/Run_and_exit_successfully 397s INFO Starting WSL Pro Service version Dev 397s === CONT TestRun/Run_and_return_usage_error 397s INFO Starting WSL Pro Service version Dev 397s ERROR context.Background Error requested 397s === CONT TestRun/Run_and_return_error 397s === CONT TestRun/Run_and_usage_error_only_does_not_fail 397s INFO Starting WSL Pro Service version Dev 397s INFO Starting WSL Pro Service version Dev 397s ERROR context.Background Error requested 397s --- PASS: TestRun (0.00s) 397s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 397s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 397s --- PASS: TestRun/Run_and_return_error (0.10s) 397s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 397s PASS 397s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.423s 412s === RUN TestHelp 412s time="2025-03-15T17:40:01Z" level=debug msg="No Landscape configuration detected: nothing to do" 412s --- PASS: TestHelp (0.00s) 412s === RUN TestCompletion 412s time="2025-03-15T17:40:01Z" level=debug msg="No Landscape configuration detected: nothing to do" 412s --- PASS: TestCompletion (0.00s) 412s === RUN TestVersion 412s --- PASS: TestVersion (0.00s) 412s === RUN TestConfigBadArg 412s --- PASS: TestConfigBadArg (0.00s) 412s === RUN TestConfigArg 412s --- PASS: TestConfigArg (0.00s) 412s === RUN TestConfigAutoDetect 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 412s --- PASS: TestConfigAutoDetect (0.00s) 412s === RUN TestNoUsageError 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 412s --- PASS: TestNoUsageError (0.00s) 412s === RUN TestUsageError 412s === PAUSE TestUsageError 412s === RUN TestCanQuitWhenExecute 412s === PAUSE TestCanQuitWhenExecute 412s === RUN TestCanQuitTwice 412s === PAUSE TestCanQuitTwice 412s === RUN TestAppCanQuitWithoutExecute 412s === PAUSE TestAppCanQuitWithoutExecute 412s === RUN TestAppRunFailsOnComponentsCreationAndQuit 412s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 412s === RUN TestAppGetRootCmd 412s === PAUSE TestAppGetRootCmd 412s === RUN TestWithProMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithProMock (0.00s) 412s === RUN TestWithWslPathMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithWslPathMock (0.00s) 412s === RUN TestWithWslInfoMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithWslInfoMock (0.00s) 412s === RUN TestWithCmdExeMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithCmdExeMock (0.00s) 412s === CONT TestUsageError 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s === CONT TestAppRunFailsOnComponentsCreationAndQuit 412s --- PASS: TestUsageError (0.00s) 412s === CONT TestAppCanQuitWithoutExecute 412s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 412s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 412s === CONT TestCanQuitTwice 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:01Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40689" 412s time="2025-03-15T17:40:01Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build166951494/b377/wsl-pro-service.yaml" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:74 New() Building new daemon" 412s time="2025-03-15T17:40:01Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40137" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:01Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:74 New() Building new daemon" 412s time="2025-03-15T17:40:07Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 412s time="2025-03-15T17:40:07Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 412s time="2025-03-15T17:40:07Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:07Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:09Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:09Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (7.92s) 412s === CONT TestAppGetRootCmd 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s --- PASS: TestAppGetRootCmd (0.01s) 412s === CONT TestCanQuitWhenExecute 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:09Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39441" 412s time="2025-03-15T17:40:09Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build166951494/b377/wsl-pro-service.yaml" 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 412s time="2025-03-15T17:40:09Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:74 New() Building new daemon" 412s time="2025-03-15T17:40:10Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:40689" 412s time="2025-03-15T17:40:10Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 412s time="2025-03-15T17:40:10Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 412s time="2025-03-15T17:40:10Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 412s time="2025-03-15T17:40:12Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 412s time="2025-03-15T17:40:12Z" level=warning msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:185 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:208 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 412s time="2025-03-15T17:40:12Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:12Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:12Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:12Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s time="2025-03-15T17:40:12Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.e2qFGS/build.t6y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 412s time="2025-03-15T17:40:12Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.e2qFGS/build.t6y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 412s time="2025-03-15T17:40:12Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.e2qFGS/build.t6y/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 412s --- PASS: TestCanQuitTwice (10.68s) 412s time="2025-03-15T17:40:14Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 412s time="2025-03-15T17:40:15Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:15Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:16Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:39441" 412s time="2025-03-15T17:40:16Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 412s time="2025-03-15T17:40:16Z" level=warning msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:183 (*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:39441: connect: connection refused\"" 412s time="2025-03-15T17:40:16Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:208 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 412s time="2025-03-15T17:40:16Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s time="2025-03-15T17:40:16Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 412s time="2025-03-15T17:40:16Z" level=info msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 412s time="2025-03-15T17:40:16Z" level=debug msg="/tmp/autopkgtest.e2qFGS/build.t6y/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 412s --- PASS: TestCanQuitWhenExecute (6.34s) 412s PASS 412s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 14.309s 412s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 412s === RUN TestApplyProToken 412s === PAUSE TestApplyProToken 412s === RUN TestApplyLandscapeConfig 412s === PAUSE TestApplyLandscapeConfig 412s === RUN TestWithProMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithProMock (0.00s) 412s === RUN TestWithLandscapeConfigMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithLandscapeConfigMock (0.00s) 412s === RUN TestWithWslPathMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithWslPathMock (0.00s) 412s === RUN TestWithWslInfoMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithWslInfoMock (0.00s) 412s === RUN TestWithCmdExeMock 412s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 412s --- SKIP: TestWithCmdExeMock (0.00s) 412s === CONT TestApplyProToken 412s === RUN TestApplyProToken/Success_attaching 412s === CONT TestApplyLandscapeConfig 412s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 412s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 412s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 412s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 412s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 412s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 412s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 412s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 412s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 412s time="2025-03-15T17:40:03Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 412s === PAUSE TestApplyProToken/Success_attaching 412s === RUN TestApplyProToken/Success_detaching 412s === PAUSE TestApplyProToken/Success_detaching 412s === RUN TestApplyProToken/Error_calling_pro_detach 412s === PAUSE TestApplyProToken/Error_calling_pro_detach 412s === RUN TestApplyProToken/Error_calling_pro_attach 412s === PAUSE TestApplyProToken/Error_calling_pro_attach 412s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 412s time="2025-03-15T17:40:03Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 412s === CONT TestApplyProToken/Success_attaching 412s time="2025-03-15T17:40:05Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 412s === CONT TestApplyProToken/Error_calling_pro_attach 412s time="2025-03-15T17:40:05Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 412s === CONT TestApplyProToken/Error_calling_pro_detach 412s time="2025-03-15T17:40:10Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 412s === CONT TestApplyProToken/Success_detaching 412s time="2025-03-15T17:40:10Z" level=info msg="ApplyProToken: Received empty token: detaching" 412s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 412s time="2025-03-15T17:40:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 412s --- PASS: TestApplyProToken (0.00s) 412s --- PASS: TestApplyProToken/Error_calling_pro_attach (4.40s) 412s --- PASS: TestApplyProToken/Success_attaching (4.61s) 412s --- PASS: TestApplyProToken/Error_calling_pro_detach (2.40s) 412s --- PASS: TestApplyProToken/Success_detaching (2.35s) 412s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 412s time="2025-03-15T17:40:12Z" level=info msg="ApplyLandscapeConfig: received config: registering" 412s --- PASS: TestApplyLandscapeConfig (0.00s) 412s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (2.33s) 412s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (2.45s) 412s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (1.72s) 412s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (1.69s) 412s PASS 412s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 11.018s 412s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 504s === RUN TestNew 504s === PAUSE TestNew 504s === RUN TestServe 504s === PAUSE TestServe 504s === RUN TestServeAndQuit 504s === PAUSE TestServeAndQuit 504s === RUN TestReconnection 504s === PAUSE TestReconnection 504s === RUN TestWithProMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithProMock (0.00s) 504s === RUN TestWithWslPathMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithWslPathMock (0.00s) 504s === RUN TestWithWslInfoMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithWslInfoMock (0.00s) 504s === RUN TestWithCmdExeMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithCmdExeMock (0.00s) 504s === CONT TestNew 504s === RUN TestNew/Success 504s === PAUSE TestNew/Success 504s === RUN TestNew/Error_when_WslPath_returns_error 504s === PAUSE TestNew/Error_when_WslPath_returns_error 504s === CONT TestServeAndQuit 504s === RUN TestServeAndQuit/Success_with_graceful_quit 504s === CONT TestReconnection 504s === PAUSE TestServeAndQuit/Success_with_graceful_quit 504s === RUN TestServeAndQuit/Success_with_forceful_quit 504s === PAUSE TestServeAndQuit/Success_with_forceful_quit 504s === RUN TestServeAndQuit/Success_with_double_quit 504s === PAUSE TestServeAndQuit/Success_with_double_quit 504s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 504s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 504s === RUN TestReconnection/Success_connecting_after_failing_to_connect 504s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 504s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 504s === CONT TestServe 504s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 504s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 504s === RUN TestServe/No_connection_because_the_port_file_has_port_0 504s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 504s === RUN TestServe/No_connection_because_there_is_no_server 504s === PAUSE TestServe/No_connection_because_there_is_no_server 504s === RUN TestServe/No_connection_because_there_are_no_certificates 504s === PAUSE TestServe/No_connection_because_there_are_no_certificates 504s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 504s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 504s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 504s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 504s === RUN TestServe/Error_because_the_context_is_pre-cancelled 504s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 504s === RUN TestServe/Error_because_the_notifier_returns_an_error 504s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 504s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 504s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 504s === RUN TestServe/Success 504s === PAUSE TestServe/Success 504s === RUN TestServe/Success_with_systemd_notifier_returning_true 504s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 504s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 504s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 504s === RUN TestServe/No_connection_because_the_port_file_is_empty 504s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 504s === CONT TestNew/Success 504s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 504s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 504s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 504s === CONT TestNew/Error_when_WslPath_returns_error 504s time="2025-03-15T17:40:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:15Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:15Z" level=debug msg="Building new daemon" 504s === CONT TestServeAndQuit/Success_with_graceful_quit 504s time="2025-03-15T17:40:18Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34651" 504s time="2025-03-15T17:40:18Z" level=debug msg="Building new daemon" 504s --- PASS: TestNew (0.00s) 504s --- PASS: TestNew/Error_when_WslPath_returns_error (3.29s) 504s --- PASS: TestNew/Success (3.33s) 504s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 504s time="2025-03-15T17:40:18Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33711" 504s time="2025-03-15T17:40:18Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:22Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:22Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:22Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:22Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:22Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s === CONT TestServeAndQuit/Success_with_double_quit 504s time="2025-03-15T17:40:22Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46595" 504s time="2025-03-15T17:40:22Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:23Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:23Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:23Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:25Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34651" 504s time="2025-03-15T17:40:25Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:25Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:25Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:25Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:27Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:27Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:27Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:27Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:27Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:27Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:27Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:27Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:27Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:27Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:40:27Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:27Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:27Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:40:27Z" level=debug msg="All connections have now ended." 504s time="2025-03-15T17:40:27Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:27Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:27Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s === CONT TestServeAndQuit/Success_with_forceful_quit 504s time="2025-03-15T17:40:27Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38403" 504s time="2025-03-15T17:40:27Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:28Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46595" 504s time="2025-03-15T17:40:28Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:28Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:28Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:28Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:29Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:29Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:29Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:29Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:30Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:30Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:30Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:30Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:30Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:30Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:40:30Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:30Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:30Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:40:30Z" level=debug msg="All connections have now ended." 504s time="2025-03-15T17:40:30Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:30Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:30Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:30Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:30Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:30Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 504s time="2025-03-15T17:40:30Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42297" 504s time="2025-03-15T17:40:30Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:31Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38403" 504s time="2025-03-15T17:40:31Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:31Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:31Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:31Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:32Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:32Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:32Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:32Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:32Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:32Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:portyMcPortface\": could not parse port as an integer: strconv.Atoi: parsing \"portyMcPortface\": invalid syntax" 504s time="2025-03-15T17:40:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:32Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:32Z" level=info msg="Stopping active requests." 504s time="2025-03-15T17:40:32Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:40:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:32Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:32Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:40:32Z" level=debug msg="All connections have now ended." 504s time="2025-03-15T17:40:32Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:32Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:32Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s --- PASS: TestServeAndQuit (0.00s) 504s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (4.06s) 504s --- PASS: TestServeAndQuit/Success_with_graceful_quit (9.05s) 504s --- PASS: TestServeAndQuit/Success_with_double_quit (7.47s) 504s --- PASS: TestServeAndQuit/Success_with_forceful_quit (5.04s) 504s === CONT TestReconnection/Success_connecting_after_failing_to_connect 504s time="2025-03-15T17:40:32Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:32Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:33Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:33Z" 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" 504s time="2025-03-15T17:40:33Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:40:33Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:33Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:33Z" level=debug msg="All connections have now ended." 504s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 504s time="2025-03-15T17:40:33Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:33Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:34Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:34Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:34Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:34Z" 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_connect687853463/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect687853463/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 504s time="2025-03-15T17:40:34Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:34Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42547" 504s time="2025-03-15T17:40:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44601" 504s time="2025-03-15T17:40:35Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:35Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:35Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:35Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:35Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44601" 504s time="2025-03-15T17:40:36Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:36Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:36Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:36Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42547" 504s time="2025-03-15T17:40:36Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:36Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:36Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:36Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:38Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:38Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:38Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:38Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 504s time="2025-03-15T17:40:38Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:40:38Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:38Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:38Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:38Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:38Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:38Z" level=info msg="Stopping active requests." 504s time="2025-03-15T17:40:38Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:40:38Z" level=debug msg="All connections have now ended." 504s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 504s time="2025-03-15T17:40:38Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:38Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33381" 504s time="2025-03-15T17:40:40Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:40Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:40Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:40Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33381" 504s time="2025-03-15T17:40:40Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:40Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:40Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:40Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:41Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:41Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:41Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:41Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:42Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 504s time="2025-03-15T17:40:42Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:42Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:42Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:42Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:42Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42573" 504s time="2025-03-15T17:40:43Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:43Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:43Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42573" 504s time="2025-03-15T17:40:43Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:43Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:43Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:43Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:40:44Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:40:44Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:44Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:44Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:45Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:45Z" level=info msg="Stopping active requests." 504s time="2025-03-15T17:40:45Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 504s time="2025-03-15T17:40:45Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:40:45Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:40:45Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:40:45Z" level=debug msg="All connections have now ended." 504s time="2025-03-15T17:40:45Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:45Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:45Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s === CONT TestServe/Error_because_the_notifier_returns_an_error 504s time="2025-03-15T17:40:45Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46279" 504s time="2025-03-15T17:40:45Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:46Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 504s time="2025-03-15T17:40:46Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:46Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:46Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_the_port_file_is_empty 504s time="2025-03-15T17:40:46Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42733" 504s time="2025-03-15T17:40:46Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:48Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:48Z" 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" 504s time="2025-03-15T17:40:48Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:49Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:49Z" 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" 504s time="2025-03-15T17:40:49Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:40:49Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:49Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:49Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 504s time="2025-03-15T17:40:49Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44903" 504s time="2025-03-15T17:40:49Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:53Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:53Z" 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_exist3430343483/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3430343483/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 504s time="2025-03-15T17:40:53Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:40:54Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:40:54Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:40:54Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/Success_with_systemd_notifier_returning_true 504s time="2025-03-15T17:40:54Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:40:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39145" 504s time="2025-03-15T17:40:54Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:40:57Z" level=debug msg="Ready state sent to systemd" 504s time="2025-03-15T17:40:57Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:40:57Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:40:59Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39145" 504s time="2025-03-15T17:40:59Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:40:59Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:40:59Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:40:59Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:41:01Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:41:01Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:41:01Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:41:01Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:41:02Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:02Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:02Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:41:02Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:02Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:41:02Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:41:02Z" level=debug msg="All connections have now ended." 504s time="2025-03-15T17:41:02Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s === CONT TestServe/Success 504s time="2025-03-15T17:41:02Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:02Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:02Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43663" 504s time="2025-03-15T17:41:02Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:06Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:08Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43663" 504s time="2025-03-15T17:41:08Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:41:08Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:41:08Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:41:10Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:41:10Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:41:10Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:41:10Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:41:11Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:11Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:41:11Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:11Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:11Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:11Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:11Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:11Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 504s time="2025-03-15T17:41:11Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36849" 504s time="2025-03-15T17:41:11Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:15Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:17Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:17Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:17Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 504s time="2025-03-15T17:41:17Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43857" 504s time="2025-03-15T17:41:17Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:20Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:20Z" 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" 504s time="2025-03-15T17:41:20Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:21Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:21Z" 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" 504s time="2025-03-15T17:41:21Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:41:21Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:21Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:21Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/Error_because_the_context_is_pre-cancelled 504s time="2025-03-15T17:41:21Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:21Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37729" 504s time="2025-03-15T17:41:21Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:25Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:25Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:25Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 504s time="2025-03-15T17:41:25Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44953" 504s time="2025-03-15T17:41:25Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:28Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:30Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44953" 504s time="2025-03-15T17:41:30Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file4239050515/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 504s time="2025-03-15T17:41:30Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:30Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:30Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:30Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_there_is_no_server 504s time="2025-03-15T17:41:30Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39561" 504s time="2025-03-15T17:41:30Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:34Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39561" 504s time="2025-03-15T17:41:36Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:41:36Z" 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:39561: connect: connection refused\"" 504s time="2025-03-15T17:41:36Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:36Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:36Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:36Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_there_are_no_certificates 504s time="2025-03-15T17:41:36Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34221" 504s time="2025-03-15T17:41:36Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:40Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:41Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34221" 504s time="2025-03-15T17:41:41Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates4138732786/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 504s time="2025-03-15T17:41:41Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:42Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:42Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:42Z" level=debug msg="All connections have now ended." 504s === CONT TestServe/No_connection_because_the_port_file_has_port_0 504s time="2025-03-15T17:41:42Z" level=debug msg="No Landscape configuration detected: nothing to do" 504s time="2025-03-15T17:41:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35029" 504s time="2025-03-15T17:41:42Z" level=debug msg="Building new daemon" 504s time="2025-03-15T17:41:43Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 504s time="2025-03-15T17:41:43Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:43Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:41:43Z" 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_dropped2317403730/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped2317403730/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 504s time="2025-03-15T17:41:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:43Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:41:43Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:43Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:43Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34029" 504s time="2025-03-15T17:41:44Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:44Z" level=debug msg="Updated systemd status to \"Connecting\"" 504s time="2025-03-15T17:41:46Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:46Z" 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" 504s time="2025-03-15T17:41:46Z" level=info msg="Reconnecting to Windows host in 1 seconds" 504s time="2025-03-15T17:41:46Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34029" 504s time="2025-03-15T17:41:46Z" level=info msg="Daemon: completed connection to Windows Agent" 504s time="2025-03-15T17:41:46Z" level=debug msg="Updated systemd status to \"Connected\"" 504s time="2025-03-15T17:41:46Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 504s time="2025-03-15T17:41:46Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 504s time="2025-03-15T17:41:47Z" level=info msg="Daemon: connecting to Windows Agent" 504s time="2025-03-15T17:41:47Z" 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" 504s time="2025-03-15T17:41:47Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:41:47Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:47Z" level=info msg="Waiting for active requests to close." 504s time="2025-03-15T17:41:47Z" level=debug msg="All connections have now ended." 504s --- PASS: TestServe (0.00s) 504s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (3.29s) 504s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (1.62s) 504s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (2.73s) 504s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (4.66s) 504s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (8.72s) 504s --- PASS: TestServe/Success (8.62s) 504s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (5.98s) 504s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (4.45s) 504s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (3.31s) 504s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (5.44s) 504s --- PASS: TestServe/No_connection_because_there_is_no_server (5.91s) 504s --- PASS: TestServe/No_connection_because_there_are_no_certificates (5.60s) 504s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (5.10s) 504s time="2025-03-15T17:41:48Z" level=debug msg="Server: sent preface messages to all streams" 504s time="2025-03-15T17:41:48Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:41:48Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:41:48Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:41:48Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:48Z" level=info msg="Stopping daemon requested." 504s time="2025-03-15T17:41:48Z" level=info msg="Stopping active requests." 504s time="2025-03-15T17:41:48Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:48Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:41:48Z" level=warning msg="Daemon: disconnected from Windows host" 504s time="2025-03-15T17:41:48Z" level=info msg="Reconnecting to Windows host in 2 seconds" 504s time="2025-03-15T17:41:48Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 504s time="2025-03-15T17:41:48Z" level=debug msg="Updated systemd status to \"Stopped\"" 504s time="2025-03-15T17:41:48Z" level=debug msg="All connections have now ended." 504s --- PASS: TestReconnection (0.00s) 504s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (5.61s) 504s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (6.64s) 504s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (75.07s) 504s PASS 504s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 93.256s 504s === RUN TestConnect 504s === PAUSE TestConnect 504s === RUN TestSendAndRecv 504s --- PASS: TestSendAndRecv (0.41s) 504s === RUN TestServe 504s === PAUSE TestServe 504s === RUN TestStop 504s === PAUSE TestStop 504s === RUN TestWithProMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithProMock (0.00s) 504s === RUN TestWithWslPathMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithWslPathMock (0.00s) 504s === RUN TestWithWslInfoMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithWslInfoMock (0.00s) 504s === RUN TestWithCmdExeMock 504s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 504s --- SKIP: TestWithCmdExeMock (0.00s) 504s === CONT TestConnect 504s === RUN TestConnect/Success 504s === PAUSE TestConnect/Success 504s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 504s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 504s === CONT TestConnect/Success 504s === CONT TestStop 504s time="2025-03-15T17:40:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44595" 504s === CONT TestServe 504s time="2025-03-15T17:40:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34479" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: Connected ready" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 504s time="2025-03-15T17:40:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 504s --- PASS: TestServe (9.09s) 504s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 504s time="2025-03-15T17:40:28Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:28Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:28Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:31Z" level=warning msg="Streamserver: could not gather info after command completion: could not obtain pro status: pro status: /usr/bin/bash: error: context canceled.\n Stdout: \n Stderr: " 504s time="2025-03-15T17:40:31Z" level=warning msg="Streamserver: could not stream back info after command completion: EOF" 504s time="2025-03-15T17:40:31Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 504s --- PASS: TestStop (13.01s) 504s time="2025-03-15T17:40:31Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 504s time="2025-03-15T17:40:31Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 504s --- PASS: TestConnect (0.00s) 504s --- PASS: TestConnect/Success (0.30s) 504s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.02s) 504s PASS 504s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 29.865s 511s === RUN TestNew 511s === PAUSE TestNew 511s === RUN TestInfo 511s === PAUSE TestInfo 511s === RUN TestWslDistroName 511s === PAUSE TestWslDistroName 511s === RUN TestUserProfileDir 511s === PAUSE TestUserProfileDir 511s === RUN TestProStatus 511s === PAUSE TestProStatus 511s === RUN TestProAttach 511s === PAUSE TestProAttach 511s === RUN TestProDetach 511s === PAUSE TestProDetach 511s === RUN TestLandscapeEnable 511s === PAUSE TestLandscapeEnable 511s === RUN TestWindowsHostAddress 511s === PAUSE TestWindowsHostAddress 511s === RUN TestLandscapeDisable 511s === PAUSE TestLandscapeDisable 511s === RUN TestEnsureValidLandscapeConfig 511s === PAUSE TestEnsureValidLandscapeConfig 511s === RUN TestRealBackend 511s === PAUSE TestRealBackend 511s === RUN TestWithProMock 511s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 511s --- SKIP: TestWithProMock (0.00s) 511s === RUN TestWithLandscapeConfigMock 511s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 511s --- SKIP: TestWithLandscapeConfigMock (0.00s) 511s === RUN TestWithWslPathMock 511s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 511s --- SKIP: TestWithWslPathMock (0.00s) 511s === RUN TestWithWslInfoMock 511s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 511s --- SKIP: TestWithWslInfoMock (0.00s) 511s === RUN TestWithCmdExeMock 511s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 511s --- SKIP: TestWithCmdExeMock (0.00s) 511s === CONT TestNew 511s === RUN TestNew/Return_a_new_system 511s === PAUSE TestNew/Return_a_new_system 511s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 511s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 511s === CONT TestNew/Return_a_new_system 511s === CONT TestProDetach 511s === RUN TestProDetach/success_on_unattached_distro 511s === PAUSE TestProDetach/success_on_unattached_distro 511s === RUN TestProDetach/success_on_attached_distro 511s === PAUSE TestProDetach/success_on_attached_distro 511s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 511s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 511s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 511s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 511s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 511s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 511s === CONT TestProDetach/success_on_unattached_distro 511s === CONT TestProAttach 511s === RUN TestProAttach/success 511s === PAUSE TestProAttach/success 511s === RUN TestProAttach/error_on_'pro_attach'_error 511s === PAUSE TestProAttach/error_on_'pro_attach'_error 511s === CONT TestProAttach/success 511s === CONT TestRealBackend 511s --- PASS: TestRealBackend (0.00s) 511s === CONT TestEnsureValidLandscapeConfig 511s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 511s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 511s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 511s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 511s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 511s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 511s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 511s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 511s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 511s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 511s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 511s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 511s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 511s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 511s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 511s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 511s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 511s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 511s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 511s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 511s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 511s === CONT TestLandscapeDisable 511s === RUN TestLandscapeDisable/Success 511s === PAUSE TestLandscapeDisable/Success 511s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 511s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 511s === CONT TestWindowsHostAddress 511s === RUN TestWindowsHostAddress/Without_NAT 511s === PAUSE TestWindowsHostAddress/Without_NAT 511s === RUN TestWindowsHostAddress/With_NAT 511s === PAUSE TestWindowsHostAddress/With_NAT 511s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 511s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 511s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 511s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 511s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 511s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 511s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 511s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 511s === CONT TestLandscapeEnable 511s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 511s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 511s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 511s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 511s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 511s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 511s === RUN TestLandscapeEnable/Regular_with_additional_keys 511s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 511s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 511s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 511s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 511s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 511s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 511s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 511s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 511s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 511s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 511s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 511s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 511s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 511s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 511s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 511s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 511s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 511s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 511s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 511s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 511s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 511s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 511s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 511s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 511s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 511s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 511s === CONT TestProDetach/success_on_attached_distro 511s === CONT TestWslDistroName 511s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 511s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 511s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 511s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 511s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 511s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 511s === RUN TestWslDistroName/Success_using_wslpath 511s === PAUSE TestWslDistroName/Success_using_wslpath 511s === CONT TestProStatus 511s === RUN TestProStatus/success_on_unattached_distro 511s === PAUSE TestProStatus/success_on_unattached_distro 511s === RUN TestProStatus/success_on_attached_distro 511s === PAUSE TestProStatus/success_on_attached_distro 511s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 511s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 511s === RUN TestProStatus/error_on_'pro_attach'_error 511s === PAUSE TestProStatus/error_on_'pro_attach'_error 511s === CONT TestUserProfileDir 511s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 511s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 511s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 511s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 511s === RUN TestUserProfileDir/Error_on_cmd.exe_error 511s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 511s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 511s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 511s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 511s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 511s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 511s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 511s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 511s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 511s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 511s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 511s === RUN TestUserProfileDir/Error_on_wslpath_error 511s === PAUSE TestUserProfileDir/Error_on_wslpath_error 511s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 511s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 511s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 511s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 511s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 511s time="2025-03-15T17:40:54Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: could not register distro to Landscape: could not parse config: key-value delimiter not found: {\n" 511s --- PASS: TestNew (0.00s) 511s --- PASS: TestNew/Return_a_new_system (0.00s) 511s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 511s === CONT TestInfo 511s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 511s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 511s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 511s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 511s === RUN TestInfo/Success 511s === PAUSE TestInfo/Success 511s === RUN TestInfo/Error_when_WslDistroName_fails 511s === PAUSE TestInfo/Error_when_WslDistroName_fails 511s === RUN TestInfo/Error_when_pro_status_command_fails 511s === PAUSE TestInfo/Error_when_pro_status_command_fails 511s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 511s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 511s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 511s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 511s === CONT TestProAttach/error_on_'pro_attach'_error 511s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 511s --- PASS: TestProDetach (0.00s) 511s --- PASS: TestProDetach/success_on_unattached_distro (1.97s) 511s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.70s) 511s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.86s) 511s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.21s) 511s --- PASS: TestProDetach/success_on_attached_distro (1.48s) 511s --- PASS: TestProAttach (0.00s) 511s --- PASS: TestProAttach/success (2.04s) 511s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.73s) 511s === CONT TestLandscapeDisable/Success 511s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 511s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 511s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 511s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 511s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 511s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 511s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 511s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 511s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 511s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 511s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 511s === NAME TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 511s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 511s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 511s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 511s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 511s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 511s === CONT TestWindowsHostAddress/Without_NAT 511s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 511s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (3.33s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (0.00s) 511s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (1.74s) 511s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (3.50s) 511s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (2.81s) 511s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (1.24s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (1.69s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (1.80s) 511s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (0.01s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.00s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (0.00s) 511s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (1.91s) 511s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (3.58s) 511s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (0.00s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (1.81s) 511s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (3.13s) 511s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (3.24s) 511s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 511s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 511s --- PASS: TestLandscapeDisable (0.00s) 511s --- PASS: TestLandscapeDisable/Success (1.80s) 511s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.65s) 511s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 511s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 511s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 511s === CONT TestWindowsHostAddress/With_NAT 511s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 511s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 511s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 511s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 511s --- PASS: TestWindowsHostAddress (0.00s) 511s --- PASS: TestWindowsHostAddress/Without_NAT (1.78s) 511s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.74s) 511s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.92s) 511s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.66s) 511s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.90s) 511s --- PASS: TestWindowsHostAddress/With_NAT (1.89s) 511s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 511s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 511s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 511s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 511s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 511s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 511s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 511s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 511s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 511s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 511s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 511s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 511s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 511s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 511s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 511s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 511s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 511s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 511s === NAME TestLandscapeEnable/Refresh_computer_title_if_changed 511s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 511s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 511s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 511s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 511s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 511s === CONT TestLandscapeEnable/Regular_with_additional_keys 511s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 511s === NAME TestLandscapeEnable/Regular_with_additional_keys 511s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 511s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 511s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 511s --- PASS: TestLandscapeEnable (0.00s) 511s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.00s) 511s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (1.72s) 511s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (3.02s) 511s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (3.25s) 511s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.60s) 511s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (1.76s) 511s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (3.57s) 511s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (3.30s) 511s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 511s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (1.75s) 511s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (1.72s) 511s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (1.62s) 511s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (3.33s) 511s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (1.76s) 511s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (3.20s) 511s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (3.18s) 511s === CONT TestWslDistroName/Success_using_wslpath 511s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 511s === CONT TestProStatus/success_on_unattached_distro 511s === CONT TestProStatus/error_on_'pro_attach'_error 511s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 511s --- PASS: TestWslDistroName (0.00s) 511s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.01s) 511s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.61s) 511s --- PASS: TestWslDistroName/Success_using_wslpath (1.77s) 511s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (2.01s) 511s === CONT TestProStatus/success_on_attached_distro 511s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 511s --- PASS: TestProStatus (0.00s) 511s --- PASS: TestProStatus/success_on_unattached_distro (1.56s) 511s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.43s) 511s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.84s) 511s --- PASS: TestProStatus/success_on_attached_distro (1.67s) 511s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 511s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 511s === CONT TestUserProfileDir/Error_on_wslpath_error 511s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 511s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 511s system_test.go:245: Removing default proc/mounts 511s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 511s system_test.go:245: Removing default proc/mounts 511s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 511s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 511s === CONT TestUserProfileDir/Error_on_cmd.exe_error 511s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 511s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 511s --- PASS: TestUserProfileDir (0.00s) 511s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (3.38s) 511s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (3.86s) 511s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (3.95s) 511s --- PASS: TestUserProfileDir/Error_on_wslpath_error (3.38s) 511s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 511s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 511s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (1.46s) 511s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (2.34s) 511s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.31s) 511s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 511s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.16s) 511s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 511s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 511s === CONT TestInfo/Error_when_pro_status_command_fails 511s === CONT TestInfo/Error_when_WslDistroName_fails 511s === CONT TestInfo/Success 511s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 511s --- PASS: TestInfo (0.00s) 511s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.18s) 511s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.11s) 511s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.21s) 511s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.09s) 511s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.13s) 511s --- PASS: TestInfo/Success (1.19s) 511s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.02s) 511s PASS 511s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 66.337s 511s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 512s autopkgtest [17:41:56]: test command1: -----------------------] 512s autopkgtest [17:41:56]: test command1: - - - - - - - - - - results - - - - - - - - - - 512s command1 PASS 513s autopkgtest [17:41:57]: @@@@@@@@@@@@@@@@@@@@ summary 513s command1 PASS 531s nova [W] Using flock in prodstack6-arm64 531s Creating nova instance adt-plucky-arm64-wsl-pro-service-20250315-173324-juju-7f2275-prod-proposed-migration-environment-2-a4f6494f-67f3-4c36-868c-14327e314d96 from image adt/ubuntu-plucky-arm64-server-20250315.img (UUID bd6e766c-b51f-4b53-86d6-23aa4d18f524)... 531s nova [W] Timed out waiting for 2cfd7a4e-95e0-4422-9fcb-6d2da8cea63b to get deleted.