0s autopkgtest [14:28:24]: starting date and time: 2025-03-15 14:28:24+0000 0s autopkgtest [14:28:24]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:28:24]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7d3j4o48/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-31.secgroup --name adt-plucky-s390x-wsl-pro-service-20250315-142823-juju-7f2275-prod-proposed-migration-environment-2-71cc4e51-76e5-4e9d-8c03-64f334c6a6f7 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 131s autopkgtest [14:30:35]: testbed dpkg architecture: s390x 131s autopkgtest [14:30:35]: testbed apt version: 2.9.33 131s autopkgtest [14:30:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 132s autopkgtest [14:30:36]: testbed release detected to be: None 132s autopkgtest [14:30:36]: updating testbed package index (apt update) 133s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 133s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 133s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 133s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 133s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [404 kB] 134s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 134s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB] 134s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [80.6 kB] 134s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1940 B] 134s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 134s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [342 kB] 134s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [14.4 kB] 134s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 134s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [328 B] 134s Fetched 1090 kB in 1s (729 kB/s) 135s Reading package lists... 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 136s Calculating upgrade... 136s Calculating upgrade... 136s The following packages were automatically installed and are no longer required: 136s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 136s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 136s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 136s linux-tools-6.11.0-8-generic 136s Use 'sudo apt autoremove' to remove them. 136s The following packages will be upgraded: 136s python3-jinja2 strace 136s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s Need to get 609 kB of archives. 136s After this operation, 27.6 kB of additional disk space will be used. 136s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 137s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 137s Fetched 609 kB in 1s (627 kB/s) 137s (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 ... 81428 files and directories currently installed.) 137s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 137s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 137s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 137s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 137s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 137s Setting up strace (6.13+ds-1ubuntu1) ... 137s Processing triggers for man-db (2.13.0-1) ... 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 138s Solving dependencies... 138s The following packages will be REMOVED: 138s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 138s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 138s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 138s linux-tools-6.11.0-8-generic* 138s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 138s After this operation, 167 MB disk space will be freed. 138s (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 ... 81428 files and directories currently installed.) 138s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 138s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 138s Removing libpython3.12t64:s390x (3.12.9-1) ... 138s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 138s Removing libnsl2:s390x (1.3.0-3build3) ... 138s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 138s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 139s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 139s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 139s Processing triggers for libc-bin (2.41-1ubuntu1) ... 139s (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 ... 56328 files and directories currently installed.) 139s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 139s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 140s autopkgtest [14:30:44]: upgrading testbed (apt dist-upgrade and autopurge) 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 140s Done 140s Entering ResolveByKeep 140s 140s Calculating upgrade... 141s The following packages will be upgraded: 141s libc-bin libc-dev-bin libc6 libc6-dev locales 141s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 9512 kB of archives. 141s After this operation, 8192 B of additional disk space will be used. 141s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 142s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 144s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 145s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 148s Preconfiguring packages ... 148s Fetched 9512 kB in 8s (1263 kB/s) 148s (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 ... 56326 files and directories currently installed.) 148s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 148s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 148s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 148s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 148s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 149s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 149s Setting up libc6:s390x (2.41-1ubuntu2) ... 149s (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 ... 56326 files and directories currently installed.) 149s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 149s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 149s Setting up libc-bin (2.41-1ubuntu2) ... 149s (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 ... 56326 files and directories currently installed.) 149s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 149s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 149s Setting up locales (2.41-1ubuntu2) ... 149s Generating locales (this might take a while)... 150s en_US.UTF-8... done 150s Generation complete. 150s Setting up libc-dev-bin (2.41-1ubuntu2) ... 150s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 150s Processing triggers for man-db (2.13.0-1) ... 151s Processing triggers for systemd (257.3-1ubuntu3) ... 152s Reading package lists... 152s Building dependency tree... 152s Reading state information... 152s Starting pkgProblemResolver with broken count: 0 152s Starting 2 pkgProblemResolver with broken count: 0 152s Done 152s Solving dependencies... 152s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 152s autopkgtest [14:30:56]: rebooting testbed after setup commands that affected boot 173s autopkgtest [14:31:17]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 175s autopkgtest [14:31:19]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 178s Get:1 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (dsc) [1422 B] 178s Get:2 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (tar) [1790 kB] 178s gpgv: Signature made Tue Sep 10 12:05:15 2024 UTC 178s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 178s gpgv: Can't check signature: No public key 178s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.5.dsc: no acceptable signature found 178s autopkgtest [14:31:22]: testing package wsl-pro-service version 0.1.5 179s autopkgtest [14:31:23]: build not needed 182s autopkgtest [14:31:26]: test command1: preparing testbed 182s Reading package lists... 182s Building dependency tree... 182s Reading state information... 182s Starting pkgProblemResolver with broken count: 0 182s Starting 2 pkgProblemResolver with broken count: 0 182s Done 183s The following NEW packages will be installed: 183s autoconf automake autopoint autotools-dev build-essential cpp cpp-14 183s cpp-14-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-apport 183s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-14 183s g++-14-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-14 gcc-14-s390x-linux-gnu 183s gcc-s390x-linux-gnu gettext golang-1.24-go golang-1.24-src golang-go 183s golang-src intltool-debian libarchive-zip-perl libasan8 libcc1-0 183s libdebhelper-perl libfile-stripnondeterminism-perl libgcc-14-dev libgomp1 183s libisl23 libitm1 libmpc3 libstdc++-14-dev libtool libubsan1 m4 po-debconf 183s 0 upgraded, 45 newly installed, 0 to remove and 0 not upgraded. 183s Need to get 103 MB of archives. 183s After this operation, 435 MB of additional disk space will be used. 183s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x m4 s390x 1.4.19-7 [259 kB] 183s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x autoconf all 2.72-3ubuntu1 [383 kB] 184s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x autotools-dev all 20220109.1 [44.9 kB] 184s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x automake all 1:1.17-3ubuntu1 [572 kB] 184s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x autopoint all 0.23.1-1 [619 kB] 184s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libisl23 s390x 0.27-1 [704 kB] 185s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libmpc3 s390x 1.3.1-1build2 [57.8 kB] 185s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [9572 kB] 193s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-14 s390x 14.2.0-17ubuntu3 [1028 B] 193s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x cpp-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [5556 B] 193s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x cpp s390x 4:14.2.0-1ubuntu1 [22.4 kB] 193s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libcc1-0 s390x 15-20250222-0ubuntu1 [49.2 kB] 193s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libgomp1 s390x 15-20250222-0ubuntu1 [152 kB] 193s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libitm1 s390x 15-20250222-0ubuntu1 [31.2 kB] 193s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libasan8 s390x 15-20250222-0ubuntu1 [2970 kB] 196s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libubsan1 s390x 15-20250222-0ubuntu1 [1212 kB] 198s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-14-dev s390x 14.2.0-17ubuntu3 [1037 kB] 199s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [18.7 MB] 219s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14 s390x 14.2.0-17ubuntu3 [526 kB] 219s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [1204 B] 219s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x gcc s390x 4:14.2.0-1ubuntu1 [5004 B] 219s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++-14-dev s390x 14.2.0-17ubuntu3 [2611 kB] 222s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14-s390x-linux-gnu s390x 14.2.0-17ubuntu3 [11.0 MB] 234s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x g++-14 s390x 14.2.0-17ubuntu3 [21.8 kB] 234s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x g++-s390x-linux-gnu s390x 4:14.2.0-1ubuntu1 [956 B] 234s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x g++ s390x 4:14.2.0-1ubuntu1 [1080 B] 234s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 234s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libdebhelper-perl all 13.24.1ubuntu2 [95.4 kB] 234s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libtool all 2.5.4-4 [168 kB] 235s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x dh-autoreconf all 20 [16.1 kB] 235s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 235s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-stripnondeterminism-perl all 1.14.1-2 [20.3 kB] 235s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x dh-strip-nondeterminism all 1.14.1-2 [5064 B] 235s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x debugedit s390x 1:5.1-2 [50.1 kB] 235s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x dwz s390x 0.15-1build6 [122 kB] 235s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x gettext s390x 0.23.1-1 [1065 kB] 236s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 236s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 236s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x debhelper all 13.24.1ubuntu2 [895 kB] 238s Get:40 http://ftpmaster.internal/ubuntu plucky/universe s390x dh-apport all 2.32.0-0ubuntu2 [19.3 kB] 238s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x golang-1.24-src all 1.24.1-1 [22.0 MB] 261s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x golang-1.24-go s390x 1.24.1-1 [27.8 MB] 288s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x golang-src all 2:1.24~2 [5136 B] 288s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x golang-go s390x 2:1.24~2 [44.0 kB] 288s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x dh-golang all 1.63 [24.9 kB] 289s Fetched 103 MB in 1min 46s (977 kB/s) 289s Selecting previously unselected package m4. 289s (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 ... 56326 files and directories currently installed.) 289s Preparing to unpack .../00-m4_1.4.19-7_s390x.deb ... 289s Unpacking m4 (1.4.19-7) ... 289s Selecting previously unselected package autoconf. 289s Preparing to unpack .../01-autoconf_2.72-3ubuntu1_all.deb ... 289s Unpacking autoconf (2.72-3ubuntu1) ... 289s Selecting previously unselected package autotools-dev. 289s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 289s Unpacking autotools-dev (20220109.1) ... 289s Selecting previously unselected package automake. 289s Preparing to unpack .../03-automake_1%3a1.17-3ubuntu1_all.deb ... 289s Unpacking automake (1:1.17-3ubuntu1) ... 289s Selecting previously unselected package autopoint. 289s Preparing to unpack .../04-autopoint_0.23.1-1_all.deb ... 289s Unpacking autopoint (0.23.1-1) ... 289s Selecting previously unselected package libisl23:s390x. 289s Preparing to unpack .../05-libisl23_0.27-1_s390x.deb ... 289s Unpacking libisl23:s390x (0.27-1) ... 289s Selecting previously unselected package libmpc3:s390x. 289s Preparing to unpack .../06-libmpc3_1.3.1-1build2_s390x.deb ... 289s Unpacking libmpc3:s390x (1.3.1-1build2) ... 289s Selecting previously unselected package cpp-14-s390x-linux-gnu. 289s Preparing to unpack .../07-cpp-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking cpp-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 289s Selecting previously unselected package cpp-14. 289s Preparing to unpack .../08-cpp-14_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking cpp-14 (14.2.0-17ubuntu3) ... 289s Selecting previously unselected package cpp-s390x-linux-gnu. 289s Preparing to unpack .../09-cpp-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 289s Unpacking cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 289s Selecting previously unselected package cpp. 289s Preparing to unpack .../10-cpp_4%3a14.2.0-1ubuntu1_s390x.deb ... 289s Unpacking cpp (4:14.2.0-1ubuntu1) ... 289s Selecting previously unselected package libcc1-0:s390x. 289s Preparing to unpack .../11-libcc1-0_15-20250222-0ubuntu1_s390x.deb ... 289s Unpacking libcc1-0:s390x (15-20250222-0ubuntu1) ... 289s Selecting previously unselected package libgomp1:s390x. 289s Preparing to unpack .../12-libgomp1_15-20250222-0ubuntu1_s390x.deb ... 289s Unpacking libgomp1:s390x (15-20250222-0ubuntu1) ... 289s Selecting previously unselected package libitm1:s390x. 289s Preparing to unpack .../13-libitm1_15-20250222-0ubuntu1_s390x.deb ... 289s Unpacking libitm1:s390x (15-20250222-0ubuntu1) ... 289s Selecting previously unselected package libasan8:s390x. 289s Preparing to unpack .../14-libasan8_15-20250222-0ubuntu1_s390x.deb ... 289s Unpacking libasan8:s390x (15-20250222-0ubuntu1) ... 289s Selecting previously unselected package libubsan1:s390x. 289s Preparing to unpack .../15-libubsan1_15-20250222-0ubuntu1_s390x.deb ... 289s Unpacking libubsan1:s390x (15-20250222-0ubuntu1) ... 289s Selecting previously unselected package libgcc-14-dev:s390x. 289s Preparing to unpack .../16-libgcc-14-dev_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking libgcc-14-dev:s390x (14.2.0-17ubuntu3) ... 289s Selecting previously unselected package gcc-14-s390x-linux-gnu. 289s Preparing to unpack .../17-gcc-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking gcc-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 289s Selecting previously unselected package gcc-14. 289s Preparing to unpack .../18-gcc-14_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking gcc-14 (14.2.0-17ubuntu3) ... 289s Selecting previously unselected package gcc-s390x-linux-gnu. 289s Preparing to unpack .../19-gcc-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 289s Unpacking gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 289s Selecting previously unselected package gcc. 289s Preparing to unpack .../20-gcc_4%3a14.2.0-1ubuntu1_s390x.deb ... 289s Unpacking gcc (4:14.2.0-1ubuntu1) ... 289s Selecting previously unselected package libstdc++-14-dev:s390x. 289s Preparing to unpack .../21-libstdc++-14-dev_14.2.0-17ubuntu3_s390x.deb ... 289s Unpacking libstdc++-14-dev:s390x (14.2.0-17ubuntu3) ... 290s Selecting previously unselected package g++-14-s390x-linux-gnu. 290s Preparing to unpack .../22-g++-14-s390x-linux-gnu_14.2.0-17ubuntu3_s390x.deb ... 290s Unpacking g++-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 290s Selecting previously unselected package g++-14. 290s Preparing to unpack .../23-g++-14_14.2.0-17ubuntu3_s390x.deb ... 290s Unpacking g++-14 (14.2.0-17ubuntu3) ... 290s Selecting previously unselected package g++-s390x-linux-gnu. 290s Preparing to unpack .../24-g++-s390x-linux-gnu_4%3a14.2.0-1ubuntu1_s390x.deb ... 290s Unpacking g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 290s Selecting previously unselected package g++. 290s Preparing to unpack .../25-g++_4%3a14.2.0-1ubuntu1_s390x.deb ... 290s Unpacking g++ (4:14.2.0-1ubuntu1) ... 290s Selecting previously unselected package build-essential. 290s Preparing to unpack .../26-build-essential_12.10ubuntu1_s390x.deb ... 290s Unpacking build-essential (12.10ubuntu1) ... 290s Selecting previously unselected package libdebhelper-perl. 290s Preparing to unpack .../27-libdebhelper-perl_13.24.1ubuntu2_all.deb ... 290s Unpacking libdebhelper-perl (13.24.1ubuntu2) ... 290s Selecting previously unselected package libtool. 290s Preparing to unpack .../28-libtool_2.5.4-4_all.deb ... 290s Unpacking libtool (2.5.4-4) ... 290s Selecting previously unselected package dh-autoreconf. 290s Preparing to unpack .../29-dh-autoreconf_20_all.deb ... 290s Unpacking dh-autoreconf (20) ... 290s Selecting previously unselected package libarchive-zip-perl. 290s Preparing to unpack .../30-libarchive-zip-perl_1.68-1_all.deb ... 290s Unpacking libarchive-zip-perl (1.68-1) ... 290s Selecting previously unselected package libfile-stripnondeterminism-perl. 290s Preparing to unpack .../31-libfile-stripnondeterminism-perl_1.14.1-2_all.deb ... 290s Unpacking libfile-stripnondeterminism-perl (1.14.1-2) ... 290s Selecting previously unselected package dh-strip-nondeterminism. 290s Preparing to unpack .../32-dh-strip-nondeterminism_1.14.1-2_all.deb ... 290s Unpacking dh-strip-nondeterminism (1.14.1-2) ... 290s Selecting previously unselected package debugedit. 290s Preparing to unpack .../33-debugedit_1%3a5.1-2_s390x.deb ... 290s Unpacking debugedit (1:5.1-2) ... 290s Selecting previously unselected package dwz. 290s Preparing to unpack .../34-dwz_0.15-1build6_s390x.deb ... 290s Unpacking dwz (0.15-1build6) ... 290s Selecting previously unselected package gettext. 290s Preparing to unpack .../35-gettext_0.23.1-1_s390x.deb ... 290s Unpacking gettext (0.23.1-1) ... 290s Selecting previously unselected package intltool-debian. 290s Preparing to unpack .../36-intltool-debian_0.35.0+20060710.6_all.deb ... 290s Unpacking intltool-debian (0.35.0+20060710.6) ... 290s Selecting previously unselected package po-debconf. 290s Preparing to unpack .../37-po-debconf_1.0.21+nmu1_all.deb ... 290s Unpacking po-debconf (1.0.21+nmu1) ... 290s Selecting previously unselected package debhelper. 290s Preparing to unpack .../38-debhelper_13.24.1ubuntu2_all.deb ... 290s Unpacking debhelper (13.24.1ubuntu2) ... 290s Selecting previously unselected package dh-apport. 290s Preparing to unpack .../39-dh-apport_2.32.0-0ubuntu2_all.deb ... 290s Unpacking dh-apport (2.32.0-0ubuntu2) ... 290s Selecting previously unselected package golang-1.24-src. 290s Preparing to unpack .../40-golang-1.24-src_1.24.1-1_all.deb ... 290s Unpacking golang-1.24-src (1.24.1-1) ... 291s Selecting previously unselected package golang-1.24-go. 291s Preparing to unpack .../41-golang-1.24-go_1.24.1-1_s390x.deb ... 291s Unpacking golang-1.24-go (1.24.1-1) ... 292s Selecting previously unselected package golang-src. 292s Preparing to unpack .../42-golang-src_2%3a1.24~2_all.deb ... 292s Unpacking golang-src (2:1.24~2) ... 292s Selecting previously unselected package golang-go:s390x. 292s Preparing to unpack .../43-golang-go_2%3a1.24~2_s390x.deb ... 292s Unpacking golang-go:s390x (2:1.24~2) ... 292s Selecting previously unselected package dh-golang. 292s Preparing to unpack .../44-dh-golang_1.63_all.deb ... 292s Unpacking dh-golang (1.63) ... 292s Setting up golang-1.24-src (1.24.1-1) ... 292s Setting up libarchive-zip-perl (1.68-1) ... 292s Setting up libdebhelper-perl (13.24.1ubuntu2) ... 292s Setting up m4 (1.4.19-7) ... 292s Setting up libgomp1:s390x (15-20250222-0ubuntu1) ... 292s Setting up dh-apport (2.32.0-0ubuntu2) ... 292s Setting up autotools-dev (20220109.1) ... 292s Setting up libmpc3:s390x (1.3.1-1build2) ... 292s Setting up autopoint (0.23.1-1) ... 292s Setting up golang-1.24-go (1.24.1-1) ... 292s Setting up autoconf (2.72-3ubuntu1) ... 292s Setting up libubsan1:s390x (15-20250222-0ubuntu1) ... 292s Setting up dwz (0.15-1build6) ... 292s Setting up libasan8:s390x (15-20250222-0ubuntu1) ... 292s Setting up debugedit (1:5.1-2) ... 292s Setting up libisl23:s390x (0.27-1) ... 292s Setting up golang-src (2:1.24~2) ... 292s Setting up libcc1-0:s390x (15-20250222-0ubuntu1) ... 292s Setting up libitm1:s390x (15-20250222-0ubuntu1) ... 292s Setting up automake (1:1.17-3ubuntu1) ... 292s update-alternatives: using /usr/bin/automake-1.17 to provide /usr/bin/automake (automake) in auto mode 292s Setting up libfile-stripnondeterminism-perl (1.14.1-2) ... 292s Setting up gettext (0.23.1-1) ... 292s Setting up golang-go:s390x (2:1.24~2) ... 292s Setting up intltool-debian (0.35.0+20060710.6) ... 292s Setting up cpp-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 292s Setting up cpp-14 (14.2.0-17ubuntu3) ... 292s Setting up dh-strip-nondeterminism (1.14.1-2) ... 292s Setting up libgcc-14-dev:s390x (14.2.0-17ubuntu3) ... 292s Setting up libstdc++-14-dev:s390x (14.2.0-17ubuntu3) ... 292s Setting up cpp-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 292s Setting up po-debconf (1.0.21+nmu1) ... 292s Setting up gcc-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 292s Setting up gcc-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 292s Setting up g++-14-s390x-linux-gnu (14.2.0-17ubuntu3) ... 292s Setting up cpp (4:14.2.0-1ubuntu1) ... 292s Setting up g++-s390x-linux-gnu (4:14.2.0-1ubuntu1) ... 292s Setting up gcc-14 (14.2.0-17ubuntu3) ... 292s Setting up g++-14 (14.2.0-17ubuntu3) ... 292s Setting up libtool (2.5.4-4) ... 292s Setting up gcc (4:14.2.0-1ubuntu1) ... 292s Setting up dh-autoreconf (20) ... 292s Setting up g++ (4:14.2.0-1ubuntu1) ... 292s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 292s Setting up build-essential (12.10ubuntu1) ... 292s Setting up debhelper (13.24.1ubuntu2) ... 292s Setting up dh-golang (1.63) ... 292s Processing triggers for man-db (2.13.0-1) ... 292s Processing triggers for install-info (7.1.1-1) ... 292s Processing triggers for libc-bin (2.41-1ubuntu2) ... 293s autopkgtest [14:33:17]: test command1: ./debian/tests/test 293s autopkgtest [14:33:17]: test command1: [----------------------- 325s === RUN TestRunSignal 325s === RUN TestRunSignal/Send_SIGINT_exits 325s INFO Starting WSL Pro Service version Dev 325s === RUN TestRunSignal/Send_SIGTERM_exits 325s INFO Starting WSL Pro Service version Dev 325s --- PASS: TestRunSignal (0.20s) 325s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 325s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 325s === RUN TestRun 325s === PAUSE TestRun 325s === CONT TestRun 325s === RUN TestRun/Run_and_exit_successfully 325s === PAUSE TestRun/Run_and_exit_successfully 325s === RUN TestRun/Run_and_return_error 325s === PAUSE TestRun/Run_and_return_error 325s === RUN TestRun/Run_and_return_usage_error 325s === PAUSE TestRun/Run_and_return_usage_error 325s === RUN TestRun/Run_and_usage_error_only_does_not_fail 325s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 325s === CONT TestRun/Run_and_exit_successfully 325s === CONT TestRun/Run_and_return_usage_error 325s INFO Starting WSL Pro Service version Dev 325s INFO Starting WSL Pro Service version Dev 325s === CONT TestRun/Run_and_usage_error_only_does_not_fail 325s ERROR context.Background Error requested 325s === CONT TestRun/Run_and_return_error 325s INFO Starting WSL Pro Service version Dev 325s INFO Starting WSL Pro Service version Dev 325s ERROR context.Background Error requested 325s --- PASS: TestRun (0.00s) 325s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 325s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 325s --- PASS: TestRun/Run_and_return_error (0.10s) 325s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 325s PASS 325s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.407s 335s === RUN TestHelp 335s time="2025-03-15T14:33:49Z" level=debug msg="No Landscape configuration detected: nothing to do" 335s --- PASS: TestHelp (0.00s) 335s === RUN TestCompletion 335s time="2025-03-15T14:33:49Z" level=debug msg="No Landscape configuration detected: nothing to do" 335s --- PASS: TestCompletion (0.00s) 335s === RUN TestVersion 335s --- PASS: TestVersion (0.00s) 335s === RUN TestConfigBadArg 335s --- PASS: TestConfigBadArg (0.00s) 335s === RUN TestConfigArg 335s --- PASS: TestConfigArg (0.00s) 335s === RUN TestConfigAutoDetect 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 335s --- PASS: TestConfigAutoDetect (0.00s) 335s === RUN TestNoUsageError 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 335s --- PASS: TestNoUsageError (0.00s) 335s === RUN TestUsageError 335s === PAUSE TestUsageError 335s === RUN TestCanQuitWhenExecute 335s === PAUSE TestCanQuitWhenExecute 335s === RUN TestCanQuitTwice 335s === PAUSE TestCanQuitTwice 335s === RUN TestAppCanQuitWithoutExecute 335s === PAUSE TestAppCanQuitWithoutExecute 335s === RUN TestAppRunFailsOnComponentsCreationAndQuit 335s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 335s === RUN TestAppGetRootCmd 335s === PAUSE TestAppGetRootCmd 335s === RUN TestWithProMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithProMock (0.00s) 335s === RUN TestWithWslPathMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithWslPathMock (0.00s) 335s === RUN TestWithWslInfoMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithWslInfoMock (0.00s) 335s === RUN TestWithCmdExeMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithCmdExeMock (0.00s) 335s === CONT TestUsageError 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s --- PASS: TestUsageError (0.00s) 335s === CONT TestAppGetRootCmd 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s === CONT TestAppCanQuitWithoutExecute 335s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 335s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 335s === CONT TestAppRunFailsOnComponentsCreationAndQuit 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:49Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40233" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:74 New() Building new daemon" 335s --- PASS: TestAppGetRootCmd (0.00s) 335s === CONT TestCanQuitTwice 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:49Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38537" 335s time="2025-03-15T14:33:49Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3671308334/b378/wsl-pro-service.yaml" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:49Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:74 New() Building new daemon" 335s time="2025-03-15T14:33:53Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 335s time="2025-03-15T14:33:53Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 335s time="2025-03-15T14:33:53Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:53Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (5.64s) 335s === CONT TestCanQuitWhenExecute 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45927" 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build3671308334/b378/wsl-pro-service.yaml" 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:74 New() Building new daemon" 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:38537" 335s time="2025-03-15T14:33:55Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 335s time="2025-03-15T14:33:55Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 335s time="2025-03-15T14:33:57Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 335s time="2025-03-15T14:33:57Z" level=warning msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:185 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 335s time="2025-03-15T14:33:57Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:208 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 335s time="2025-03-15T14:33:57Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s time="2025-03-15T14:33:57Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:57Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:57Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s time="2025-03-15T14:33:57Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.CKu5SC/build.xXC/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 335s time="2025-03-15T14:33:57Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:57Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:57Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s --- PASS: TestCanQuitTwice (7.48s) 335s time="2025-03-15T14:33:57Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.CKu5SC/build.xXC/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 335s time="2025-03-15T14:33:57Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.CKu5SC/build.xXC/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 335s time="2025-03-15T14:33:58Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:45927" 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 335s time="2025-03-15T14:33:59Z" level=warning msg="/tmp/autopkgtest.CKu5SC/build.xXC/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:45927: connect: connection refused\"" 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:208 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 335s time="2025-03-15T14:33:59Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 335s time="2025-03-15T14:33:59Z" level=info msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 335s time="2025-03-15T14:33:59Z" level=debug msg="/tmp/autopkgtest.CKu5SC/build.xXC/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 335s --- PASS: TestCanQuitWhenExecute (4.14s) 335s PASS 335s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 9.801s 335s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 335s === RUN TestApplyProToken 335s === PAUSE TestApplyProToken 335s === RUN TestApplyLandscapeConfig 335s === PAUSE TestApplyLandscapeConfig 335s === RUN TestWithProMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithProMock (0.00s) 335s === RUN TestWithLandscapeConfigMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithLandscapeConfigMock (0.00s) 335s === RUN TestWithWslPathMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithWslPathMock (0.00s) 335s === RUN TestWithWslInfoMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithWslInfoMock (0.00s) 335s === RUN TestWithCmdExeMock 335s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 335s --- SKIP: TestWithCmdExeMock (0.00s) 335s === CONT TestApplyProToken 335s === RUN TestApplyProToken/Success_attaching 335s === PAUSE TestApplyProToken/Success_attaching 335s === RUN TestApplyProToken/Success_detaching 335s === PAUSE TestApplyProToken/Success_detaching 335s === RUN TestApplyProToken/Error_calling_pro_detach 335s === CONT TestApplyLandscapeConfig 335s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 335s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 335s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 335s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 335s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 335s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 335s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 335s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 335s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 335s time="2025-03-15T14:33:50Z" level=info msg="ApplyLandscapeConfig: received config: registering" 335s === PAUSE TestApplyProToken/Error_calling_pro_detach 335s === RUN TestApplyProToken/Error_calling_pro_attach 335s === PAUSE TestApplyProToken/Error_calling_pro_attach 335s === CONT TestApplyProToken/Success_attaching 335s time="2025-03-15T14:33:50Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 335s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 335s time="2025-03-15T14:33:52Z" level=info msg="ApplyLandscapeConfig: received config: registering" 335s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 335s time="2025-03-15T14:33:53Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 335s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 335s time="2025-03-15T14:33:53Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 335s === CONT TestApplyProToken/Error_calling_pro_detach 335s time="2025-03-15T14:33:55Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 335s --- PASS: TestApplyLandscapeConfig (0.00s) 335s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (1.48s) 335s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (1.56s) 335s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (1.68s) 335s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (1.70s) 335s === CONT TestApplyProToken/Error_calling_pro_attach 335s time="2025-03-15T14:33:55Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 335s === CONT TestApplyProToken/Success_detaching 335s time="2025-03-15T14:33:56Z" level=info msg="ApplyProToken: Received empty token: detaching" 335s --- PASS: TestApplyProToken (0.00s) 335s --- PASS: TestApplyProToken/Success_attaching (3.00s) 335s --- PASS: TestApplyProToken/Error_calling_pro_detach (1.50s) 335s --- PASS: TestApplyProToken/Error_calling_pro_attach (2.74s) 335s --- PASS: TestApplyProToken/Success_detaching (1.29s) 335s PASS 335s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 7.479s 335s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 414s === RUN TestNew 414s === PAUSE TestNew 414s === RUN TestServe 414s === PAUSE TestServe 414s === RUN TestServeAndQuit 414s === PAUSE TestServeAndQuit 414s === RUN TestReconnection 414s === PAUSE TestReconnection 414s === RUN TestWithProMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithProMock (0.00s) 414s === RUN TestWithWslPathMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslPathMock (0.00s) 414s === RUN TestWithWslInfoMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslInfoMock (0.00s) 414s === RUN TestWithCmdExeMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithCmdExeMock (0.00s) 414s === CONT TestNew 414s === RUN TestNew/Success 414s === PAUSE TestNew/Success 414s === RUN TestNew/Error_when_WslPath_returns_error 414s === PAUSE TestNew/Error_when_WslPath_returns_error 414s === CONT TestServeAndQuit 414s === RUN TestServeAndQuit/Success_with_graceful_quit 414s === PAUSE TestServeAndQuit/Success_with_graceful_quit 414s === RUN TestServeAndQuit/Success_with_forceful_quit 414s === PAUSE TestServeAndQuit/Success_with_forceful_quit 414s === RUN TestServeAndQuit/Success_with_double_quit 414s === PAUSE TestServeAndQuit/Success_with_double_quit 414s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 414s === CONT TestReconnection 414s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 414s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 414s === RUN TestReconnection/Success_connecting_after_failing_to_connect 414s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 414s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 414s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 414s === CONT TestServe 414s === RUN TestServe/No_connection_because_there_are_no_certificates 414s === PAUSE TestServe/No_connection_because_there_are_no_certificates 414s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 414s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 414s === RUN TestServe/Error_because_the_context_is_pre-cancelled 414s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 414s === RUN TestServe/Success 414s === PAUSE TestServe/Success 414s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 414s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 414s === RUN TestServe/No_connection_because_there_is_no_server 414s === PAUSE TestServe/No_connection_because_there_is_no_server 414s === RUN TestServe/Error_because_the_notifier_returns_an_error 414s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 414s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 414s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 414s === RUN TestServe/Success_with_systemd_notifier_returning_true 414s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 414s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 414s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 414s === RUN TestServe/No_connection_because_the_port_file_is_empty 414s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 414s === RUN TestServe/No_connection_because_the_port_file_has_port_0 414s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 414s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 414s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 414s === CONT TestNew/Success 414s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 414s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 414s time="2025-03-15T14:33:58Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:33:58Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:33:58Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:33:58Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42301" 414s time="2025-03-15T14:34:01Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:34:01Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:01Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s === CONT TestServe/No_connection_because_there_are_no_certificates 414s time="2025-03-15T14:34:01Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39437" 414s time="2025-03-15T14:34:01Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:03Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42301" 414s time="2025-03-15T14:34:03Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:03Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:03Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:03Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:04Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:04Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:04Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:04Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:04Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:05Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39437" 414s time="2025-03-15T14:34:05Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates196505422/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 414s time="2025-03-15T14:34:05Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:06Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:06Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:06Z" level=debug msg="All connections have now ended." 414s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 414s time="2025-03-15T14:34:06Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:06Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38775" 414s time="2025-03-15T14:34:07Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:34:07Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:07Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:08Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38775" 414s time="2025-03-15T14:34:08Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:08Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:08Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:08Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:08Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:08Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:08Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:08Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:09Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 414s time="2025-03-15T14:34:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:09Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:09Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:09Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:09Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38183" 414s time="2025-03-15T14:34:10Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:10Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:10Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38183" 414s time="2025-03-15T14:34:10Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:10Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:10Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:10Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:11Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:11Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:11Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:11Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:11Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 414s time="2025-03-15T14:34:11Z" level=info msg="Reconnecting to Windows host in 2 seconds" 414s time="2025-03-15T14:34:11Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:11Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:11Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:11Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:11Z" level=info msg="Stopping active requests." 414s time="2025-03-15T14:34:11Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:11Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:34:11Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s === CONT TestReconnection/Success_connecting_after_failing_to_connect 414s time="2025-03-15T14:34:11Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:11Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:12Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:34:12Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:12Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:12Z" 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_connect271743585/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect271743585/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 414s time="2025-03-15T14:34:12Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:12Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33739" 414s time="2025-03-15T14:34:13Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:13Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:14Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33739" 414s time="2025-03-15T14:34:14Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:14Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:14Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:14Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:15Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:15Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:15Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:15Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:15Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:15Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:15Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:15Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:15Z" level=info msg="Stopping active requests." 414s time="2025-03-15T14:34:15Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:34:15Z" level=info msg="Reconnecting to Windows host in 2 seconds" 414s time="2025-03-15T14:34:15Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:15Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:34:15Z" level=debug msg="All connections have now ended." 414s === CONT TestServeAndQuit/Success_with_graceful_quit 414s time="2025-03-15T14:34:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42733" 414s time="2025-03-15T14:34:15Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:16Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:34:16Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:16Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:17Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42733" 414s time="2025-03-15T14:34:17Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:17Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:17Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:17Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:18Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:18Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:18Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:18Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:18Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:18Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:18Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:34:18Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:18Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:18Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:34:18Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:34:18Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:18Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:18Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s === CONT TestNew/Error_when_WslPath_returns_error 414s time="2025-03-15T14:34:18Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:18Z" level=debug msg="Building new daemon" 414s --- PASS: TestNew (0.00s) 414s --- PASS: TestNew/Success (2.57s) 414s --- PASS: TestNew/Error_when_WslPath_returns_error (1.31s) 414s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 414s time="2025-03-15T14:34:19Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43481" 414s time="2025-03-15T14:34:19Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:21Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34: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" 414s time="2025-03-15T14:34:21Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:22Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:22Z" 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" 414s time="2025-03-15T14:34:22Z" level=info msg="Reconnecting to Windows host in 2 seconds" 414s time="2025-03-15T14:34:22Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:22Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:22Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_the_port_file_has_port_0 414s time="2025-03-15T14:34:22Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36575" 414s time="2025-03-15T14:34:22Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:23Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:23Z" 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" 414s time="2025-03-15T14:34:23Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:24Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:24Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:24Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:24Z" 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" 414s time="2025-03-15T14:34:24Z" level=info msg="Reconnecting to Windows host in 2 seconds" 414s time="2025-03-15T14:34:24Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_the_port_file_is_empty 414s time="2025-03-15T14:34:24Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39057" 414s time="2025-03-15T14:34:24Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:25Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:25Z" 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" 414s time="2025-03-15T14:34:25Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:26Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:26Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:26Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 414s time="2025-03-15T14:34:26Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:26Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40641" 414s time="2025-03-15T14:34:26Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:28Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:28Z" 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_exist1719904765/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist1719904765/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 414s time="2025-03-15T14:34:28Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:29Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:29Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:29Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/Success_with_systemd_notifier_returning_true 414s time="2025-03-15T14:34:29Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34145" 414s time="2025-03-15T14:34:29Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:32Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:34:32Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:32Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:34:33Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34145" 414s time="2025-03-15T14:34:33Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:33Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:34:33Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:33Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:34Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:34Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:34Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:34Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:36Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:36Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:36Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:34:36Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:36Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:34:36Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:34:36Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:34:36Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 414s time="2025-03-15T14:34:36Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45797" 414s time="2025-03-15T14:34:36Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:36Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:36Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:39Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:40Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:40Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:40Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/Error_because_the_notifier_returns_an_error 414s time="2025-03-15T14:34:40Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38123" 414s time="2025-03-15T14:34:40Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:42Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 414s time="2025-03-15T14:34:42Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:42Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:42Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_there_is_no_server 414s time="2025-03-15T14:34:42Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41227" 414s time="2025-03-15T14:34:43Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:45Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:46Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:41227" 414s time="2025-03-15T14:34:46Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:46Z" 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:41227: connect: connection refused\"" 414s time="2025-03-15T14:34:46Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:47Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:47Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:47Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 414s time="2025-03-15T14:34:47Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:47Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42181" 414s time="2025-03-15T14:34:47Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:49Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:49Z" 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" 414s time="2025-03-15T14:34:49Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:50Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:50Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:50Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/Success 414s time="2025-03-15T14:34:50Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37061" 414s time="2025-03-15T14:34:50Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:34:53Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:34:54Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37061" 414s time="2025-03-15T14:34:54Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:34:54Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:34:54Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:34:55Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:34:55Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:55Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:55Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:57Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:34:57Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:34:57Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:34:57Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:34:57Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:34:57Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s === CONT TestServe/Error_because_the_context_is_pre-cancelled 414s time="2025-03-15T14:34:57Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:34:57Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:57Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35129" 414s time="2025-03-15T14:34:57Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:35:00Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:00Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:00Z" level=debug msg="All connections have now ended." 414s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 414s time="2025-03-15T14:35:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:35:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40647" 414s time="2025-03-15T14:35:00Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:35:02Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:35:04Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40647" 414s time="2025-03-15T14:35:04Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file3851369955/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 414s time="2025-03-15T14:35:04Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:35:04Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:04Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:04Z" level=debug msg="All connections have now ended." 414s --- PASS: TestServe (0.00s) 414s --- PASS: TestServe/No_connection_because_there_are_no_certificates (4.74s) 414s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (2.22s) 414s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (2.27s) 414s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (2.34s) 414s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (3.15s) 414s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (6.56s) 414s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (4.00s) 414s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (2.57s) 414s --- PASS: TestServe/No_connection_because_there_is_no_server (4.45s) 414s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (3.52s) 414s --- PASS: TestServe/Success (6.52s) 414s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (2.77s) 414s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (4.61s) 414s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 414s time="2025-03-15T14:35:04Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:35:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40619" 414s time="2025-03-15T14:35:04Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:35:07Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:07Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:07Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:07Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:07Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s === CONT TestServeAndQuit/Success_with_double_quit 414s time="2025-03-15T14:35:07Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:35:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32935" 414s time="2025-03-15T14:35:07Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:35:09Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:09Z" level=warning msg="Daemon: disconnected from Windows host: serve error: could not receive ProAttachCmd: rpc error: code = Unavailable desc = error reading from server: EOF" 414s time="2025-03-15T14:35:09Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:35:09Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:35:09Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped3514437655/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped3514437655/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 414s time="2025-03-15T14:35:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:35:09Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:35:09Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:09Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38705" 414s time="2025-03-15T14:35:10Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:35:10Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:35:10Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:35:10Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:35:10Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:35:11Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38705" 414s time="2025-03-15T14:35:11Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:35:11Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:35:11Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:35:11Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:35:11Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:32935" 414s time="2025-03-15T14:35:11Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:35:11Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:35:11Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:35:11Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:35:13Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:35:13Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:35:13Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:13Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:13Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:35:13Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:35:13Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:35:13Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:35:13Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:13Z" level=info msg="Stopping active requests." 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:13Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:35:13Z" level=info msg="Reconnecting to Windows host in 2 seconds" 414s time="2025-03-15T14:35:13Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:35:13Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:35:13Z" level=debug msg="All connections have now ended." 414s --- PASS: TestReconnection (0.00s) 414s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (5.28s) 414s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (3.96s) 414s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (74.82s) 414s === CONT TestServeAndQuit/Success_with_forceful_quit 414s time="2025-03-15T14:35:13Z" level=debug msg="No Landscape configuration detected: nothing to do" 414s time="2025-03-15T14:35:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35521" 414s time="2025-03-15T14:35:13Z" level=debug msg="Building new daemon" 414s time="2025-03-15T14:35:13Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:13Z" level=info msg="Waiting for active requests to close." 414s time="2025-03-15T14:35:13Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:35:16Z" level=debug msg="Ready state sent to systemd" 414s time="2025-03-15T14:35:16Z" level=info msg="Daemon: connecting to Windows Agent" 414s time="2025-03-15T14:35:16Z" level=debug msg="Updated systemd status to \"Connecting\"" 414s time="2025-03-15T14:35:17Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35521" 414s time="2025-03-15T14:35:17Z" level=info msg="Daemon: completed connection to Windows Agent" 414s time="2025-03-15T14:35:17Z" level=debug msg="Updated systemd status to \"Connected\"" 414s time="2025-03-15T14:35:17Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 414s time="2025-03-15T14:35:17Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 414s time="2025-03-15T14:35:17Z" level=debug msg="Server: sent preface messages to all streams" 414s time="2025-03-15T14:35:17Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:35:17Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:35:17Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:35:18Z" level=info msg="Stopping daemon requested." 414s time="2025-03-15T14:35:18Z" level=info msg="Stopping active requests." 414s time="2025-03-15T14:35:18Z" level=warning msg="Daemon: disconnected from Windows host" 414s time="2025-03-15T14:35:18Z" level=info msg="Reconnecting to Windows host in 1 seconds" 414s time="2025-03-15T14:35:18Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 414s time="2025-03-15T14:35:18Z" level=debug msg="Updated systemd status to \"Stopped\"" 414s time="2025-03-15T14:35:18Z" level=debug msg="All connections have now ended." 414s time="2025-03-15T14:35:18Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:18Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:35:18Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s --- PASS: TestServeAndQuit (0.00s) 414s --- PASS: TestServeAndQuit/Success_with_graceful_quit (3.05s) 414s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.71s) 414s --- PASS: TestServeAndQuit/Success_with_double_quit (6.20s) 414s --- PASS: TestServeAndQuit/Success_with_forceful_quit (4.67s) 414s PASS 414s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 79.489s 414s === RUN TestConnect 414s === PAUSE TestConnect 414s === RUN TestSendAndRecv 414s --- PASS: TestSendAndRecv (0.41s) 414s === RUN TestServe 414s === PAUSE TestServe 414s === RUN TestStop 414s === PAUSE TestStop 414s === RUN TestWithProMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithProMock (0.00s) 414s === RUN TestWithWslPathMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslPathMock (0.00s) 414s === RUN TestWithWslInfoMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslInfoMock (0.00s) 414s === RUN TestWithCmdExeMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithCmdExeMock (0.00s) 414s === CONT TestConnect 414s === RUN TestConnect/Success 414s === PAUSE TestConnect/Success 414s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 414s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 414s === CONT TestConnect/Success 414s === CONT TestStop 414s time="2025-03-15T14:34:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36251" 414s === CONT TestServe 414s time="2025-03-15T14:34:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44405" 414s time="2025-03-15T14:34:02Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:02Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:02Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:03Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 414s time="2025-03-15T14:34:03Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 414s time="2025-03-15T14:34:03Z" level=info msg="MockWindowsAgent: Connected ready" 414s time="2025-03-15T14:34:06Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:06Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:06Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s --- PASS: TestServe (4.84s) 414s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 414s time="2025-03-15T14:34:13Z" 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: " 414s time="2025-03-15T14:34:13Z" level=warning msg="Streamserver: could not stream back info after command completion: EOF" 414s time="2025-03-15T14:34:13Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:13Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 414s time="2025-03-15T14:34:13Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 414s --- PASS: TestStop (12.00s) 414s --- PASS: TestConnect (0.00s) 414s --- PASS: TestConnect/Success (0.30s) 414s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.01s) 414s PASS 414s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 25.572s 414s === RUN TestNew 414s === PAUSE TestNew 414s === RUN TestInfo 414s === PAUSE TestInfo 414s === RUN TestWslDistroName 414s === PAUSE TestWslDistroName 414s === RUN TestUserProfileDir 414s === PAUSE TestUserProfileDir 414s === RUN TestProStatus 414s === PAUSE TestProStatus 414s === RUN TestProAttach 414s === PAUSE TestProAttach 414s === RUN TestProDetach 414s === PAUSE TestProDetach 414s === RUN TestLandscapeEnable 414s === PAUSE TestLandscapeEnable 414s === RUN TestWindowsHostAddress 414s === PAUSE TestWindowsHostAddress 414s === RUN TestLandscapeDisable 414s === PAUSE TestLandscapeDisable 414s === RUN TestEnsureValidLandscapeConfig 414s === PAUSE TestEnsureValidLandscapeConfig 414s === RUN TestRealBackend 414s === PAUSE TestRealBackend 414s === RUN TestWithProMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithProMock (0.00s) 414s === RUN TestWithLandscapeConfigMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithLandscapeConfigMock (0.00s) 414s === RUN TestWithWslPathMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslPathMock (0.00s) 414s === RUN TestWithWslInfoMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithWslInfoMock (0.00s) 414s === RUN TestWithCmdExeMock 414s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 414s --- SKIP: TestWithCmdExeMock (0.00s) 414s === CONT TestNew 414s === RUN TestNew/Return_a_new_system 414s === PAUSE TestNew/Return_a_new_system 414s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 414s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 414s === CONT TestNew/Return_a_new_system 414s === CONT TestRealBackend 414s --- PASS: TestRealBackend (0.00s) 414s === CONT TestEnsureValidLandscapeConfig 414s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 414s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 414s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 414s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 414s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 414s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 414s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 414s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 414s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 414s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 414s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 414s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 414s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 414s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 414s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 414s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 414s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 414s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 414s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 414s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 414s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 414s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 414s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 414s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 414s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 414s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 414s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 414s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 414s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 414s === NAME TestEnsureValidLandscapeConfig/Regular_with_additional_keys 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/regular_with_additional_keys 414s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 414s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 414s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 414s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 414s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 414s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 414s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 414s === CONT TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 414s === CONT TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 414s === NAME TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_modify_other_sections_and_keys 414s === NAME TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 414s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_transform_windows_ssl_certificate_empty_path 414s === CONT TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 414s === CONT TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 414s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 414s === CONT TestProStatus 414s === RUN TestProStatus/success_on_unattached_distro 414s === PAUSE TestProStatus/success_on_unattached_distro 414s === RUN TestProStatus/success_on_attached_distro 414s === PAUSE TestProStatus/success_on_attached_distro 414s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 414s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 414s === RUN TestProStatus/error_on_'pro_attach'_error 414s === PAUSE TestProStatus/error_on_'pro_attach'_error 414s === CONT TestLandscapeDisable 414s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 414s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 414s === RUN TestLandscapeDisable/Success 414s === PAUSE TestLandscapeDisable/Success 414s === CONT TestWindowsHostAddress 414s === RUN TestWindowsHostAddress/Without_NAT 414s === PAUSE TestWindowsHostAddress/Without_NAT 414s === RUN TestWindowsHostAddress/With_NAT 414s === PAUSE TestWindowsHostAddress/With_NAT 414s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 414s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 414s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 414s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 414s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 414s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 414s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 414s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 414s === CONT TestLandscapeEnable 414s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 414s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 414s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 414s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 414s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 414s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 414s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 414s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 414s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 414s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 414s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 414s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 414s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 414s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 414s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 414s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 414s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 414s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 414s === RUN TestLandscapeEnable/Regular_with_additional_keys 414s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 414s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 415s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 415s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 415s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 415s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 415s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 415s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 415s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 415s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 415s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 415s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 415s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 415s === CONT TestProDetach 415s === RUN TestProDetach/success_on_unattached_distro 415s === PAUSE TestProDetach/success_on_unattached_distro 415s === RUN TestProDetach/success_on_attached_distro 415s === PAUSE TestProDetach/success_on_attached_distro 415s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 415s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 415s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 415s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 415s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 415s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 415s === CONT TestProAttach 415s === RUN TestProAttach/success 415s === PAUSE TestProAttach/success 415s === RUN TestProAttach/error_on_'pro_attach'_error 415s === PAUSE TestProAttach/error_on_'pro_attach'_error 415s === CONT TestInfo 415s === RUN TestInfo/Error_when_WslDistroName_fails 415s === PAUSE TestInfo/Error_when_WslDistroName_fails 415s === RUN TestInfo/Error_when_pro_status_command_fails 415s === PAUSE TestInfo/Error_when_pro_status_command_fails 415s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 415s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 415s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 415s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 415s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 415s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 415s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 415s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 415s === RUN TestInfo/Success 415s === PAUSE TestInfo/Success 415s === CONT TestUserProfileDir 415s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 415s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 415s === RUN TestUserProfileDir/Error_on_cmd.exe_error 415s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 415s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 415s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 415s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 415s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 415s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 415s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 415s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 415s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 415s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 415s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 415s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 415s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 415s === RUN TestUserProfileDir/Error_on_wslpath_error 415s === PAUSE TestUserProfileDir/Error_on_wslpath_error 415s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 415s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 415s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 415s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 415s === CONT TestWslDistroName 415s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 415s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 415s === RUN TestWslDistroName/Success_using_wslpath 415s === PAUSE TestWslDistroName/Success_using_wslpath 415s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 415s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 415s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 415s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 415s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 415s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 415s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path_with_forward_slash 415s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 415s time="2025-03-15T14:34:37Z" 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" 415s --- PASS: TestNew (0.00s) 415s --- PASS: TestNew/Return_a_new_system (0.00s) 415s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 415s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 415s === CONT TestProStatus/success_on_unattached_distro 415s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 415s --- PASS: TestEnsureValidLandscapeConfig (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications (1.43s) 415s --- PASS: TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl (1.47s) 415s --- PASS: TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed (0.84s) 415s --- PASS: TestEnsureValidLandscapeConfig/Regular_with_additional_keys (2.11s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path (2.33s) 415s --- PASS: TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini (2.35s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (1.43s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (2.34s) 415s --- PASS: TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys (2.16s) 415s --- PASS: TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path (0.98s) 415s --- PASS: TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash (2.14s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed (0.00s) 415s --- PASS: TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name (1.33s) 415s === CONT TestProStatus/error_on_'pro_attach'_error 415s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 415s === CONT TestProStatus/success_on_attached_distro 415s === CONT TestLandscapeDisable/Success 415s --- PASS: TestProStatus (0.00s) 415s --- PASS: TestProStatus/success_on_unattached_distro (1.28s) 415s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.31s) 415s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.39s) 415s --- PASS: TestProStatus/success_on_attached_distro (1.23s) 415s === CONT TestWindowsHostAddress/Without_NAT 415s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 415s --- PASS: TestLandscapeDisable (0.00s) 415s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.28s) 415s --- PASS: TestLandscapeDisable/Success (1.30s) 415s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 415s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 415s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 415s === CONT TestWindowsHostAddress/With_NAT 415s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 415s --- PASS: TestWindowsHostAddress (0.00s) 415s --- PASS: TestWindowsHostAddress/Without_NAT (1.26s) 415s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.14s) 415s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.36s) 415s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.19s) 415s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.16s) 415s --- PASS: TestWindowsHostAddress/With_NAT (1.19s) 415s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 415s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 415s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 415s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 415s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 415s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 415s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 415s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 415s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 415s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 415s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 415s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 415s === NAME TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 415s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 415s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 415s === CONT TestLandscapeEnable/Regular_with_additional_keys 415s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 415s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 415s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 415s === NAME TestLandscapeEnable/Regular_with_additional_keys 415s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 415s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 415s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 415s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 415s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 415s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 415s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 415s === CONT TestProDetach/success_on_unattached_distro 415s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 415s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 415s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 415s --- PASS: TestLandscapeEnable (0.00s) 415s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (1.23s) 415s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (0.85s) 415s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (2.16s) 415s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (2.38s) 415s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (2.53s) 415s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (0.85s) 415s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (0.89s) 415s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (2.61s) 415s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (2.58s) 415s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (1.26s) 415s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (2.39s) 415s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (1.24s) 415s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (0.91s) 415s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.00s) 415s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 415s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (1.86s) 415s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 415s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 415s === CONT TestProDetach/success_on_attached_distro 415s === CONT TestProAttach/success 415s === CONT TestProAttach/error_on_'pro_attach'_error 415s --- PASS: TestProDetach (0.00s) 415s --- PASS: TestProDetach/success_on_unattached_distro (1.01s) 415s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.29s) 415s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.15s) 415s --- PASS: TestProDetach/success_on_attached_distro (1.23s) 415s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.28s) 415s === CONT TestInfo/Error_when_WslDistroName_fails 415s --- PASS: TestProAttach (0.00s) 415s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.30s) 415s --- PASS: TestProAttach/success (1.33s) 415s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 415s === CONT TestInfo/Success 415s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 415s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 415s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 415s === CONT TestInfo/Error_when_pro_status_command_fails 415s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 415s system_test.go:245: Removing default proc/mounts 415s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 415s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 415s --- PASS: TestInfo (0.00s) 415s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.26s) 415s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.28s) 415s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.23s) 415s --- PASS: TestInfo/Success (1.33s) 415s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (0.95s) 415s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (0.96s) 415s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.22s) 415s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 415s === CONT TestUserProfileDir/Error_on_wslpath_error 415s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 415s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 415s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 415s system_test.go:245: Removing default proc/mounts 415s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 415s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 415s === CONT TestUserProfileDir/Error_on_cmd.exe_error 415s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 415s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 415s --- PASS: TestUserProfileDir (0.00s) 415s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 415s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 415s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.67s) 415s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.62s) 415s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (1.22s) 415s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.35s) 415s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 415s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (3.29s) 415s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (3.30s) 415s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.14s) 415s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.41s) 415s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 415s === CONT TestWslDistroName/Success_using_wslpath 415s --- PASS: TestWslDistroName (0.00s) 415s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 415s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.30s) 415s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.25s) 415s --- PASS: TestWslDistroName/Success_using_wslpath (1.21s) 415s PASS 415s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 49.694s 415s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 415s autopkgtest [14:35:19]: test command1: -----------------------] 416s autopkgtest [14:35:20]: test command1: - - - - - - - - - - results - - - - - - - - - - 416s command1 PASS 416s autopkgtest [14:35:20]: @@@@@@@@@@@@@@@@@@@@ summary 416s command1 PASS 421s nova [W] Using flock in prodstack6-s390x 421s Creating nova instance adt-plucky-s390x-wsl-pro-service-20250315-142823-juju-7f2275-prod-proposed-migration-environment-2-71cc4e51-76e5-4e9d-8c03-64f334c6a6f7 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 421s nova [W] Timed out waiting for bbf8280e-d135-4bad-bfd1-37060133b323 to get deleted.