0s autopkgtest [07:29:48]: starting date and time: 2024-07-19 07:29:48+0000 0s autopkgtest [07:29:48]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [07:29:48]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lwqg4_p_/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:ubuntu-advantage-tools --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/33.2~24.04' -- lxd -r lxd-armhf-10.145.243.252 lxd-armhf-10.145.243.252:autopkgtest/ubuntu/noble/armhf 56s autopkgtest [07:30:44]: testbed dpkg architecture: armhf 59s autopkgtest [07:30:47]: testbed apt version: 2.7.14build2 59s autopkgtest [07:30:47]: @@@@@@@@@@@@@@@@@@@@ test bed setup 71s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 72s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [33.0 kB] 73s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [9944 B] 73s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [5700 B] 73s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [45.1 kB] 73s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2212 B] 73s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1176 B] 73s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 73s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [45.3 kB] 73s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [2152 B] 73s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 76s Fetched 410 kB in 2s (168 kB/s) 76s Reading package lists... 97s tee: /proc/self/fd/2: Permission denied 121s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 121s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 121s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 121s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 127s Reading package lists... 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 128s Calculating upgrade... 128s The following packages will be upgraded: 128s apparmor libapparmor1 libnss-systemd libpam-systemd libsystemd-shared 128s libsystemd0 libudev1 systemd systemd-dev systemd-resolved systemd-sysv 128s systemd-timesyncd ubuntu-pro-client ubuntu-pro-client-l10n udev xkb-data 129s 16 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 10.0 MB of archives. 129s After this operation, 5120 B of additional disk space will be used. 129s Get:1 http://ftpmaster.internal/ubuntu noble-updates/main armhf libnss-systemd armhf 255.4-1ubuntu8.2 [148 kB] 130s Get:2 http://ftpmaster.internal/ubuntu noble-updates/main armhf systemd-dev all 255.4-1ubuntu8.2 [104 kB] 130s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main armhf systemd-timesyncd armhf 255.4-1ubuntu8.2 [36.0 kB] 130s Get:4 http://ftpmaster.internal/ubuntu noble-updates/main armhf systemd-resolved armhf 255.4-1ubuntu8.2 [289 kB] 130s Get:5 http://ftpmaster.internal/ubuntu noble-updates/main armhf libsystemd-shared armhf 255.4-1ubuntu8.2 [2010 kB] 130s Get:6 http://ftpmaster.internal/ubuntu noble-updates/main armhf libsystemd0 armhf 255.4-1ubuntu8.2 [411 kB] 131s Get:7 http://ftpmaster.internal/ubuntu noble-updates/main armhf systemd-sysv armhf 255.4-1ubuntu8.2 [11.9 kB] 131s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main armhf libpam-systemd armhf 255.4-1ubuntu8.2 [216 kB] 131s Get:9 http://ftpmaster.internal/ubuntu noble-updates/main armhf systemd armhf 255.4-1ubuntu8.2 [3502 kB] 132s Get:10 http://ftpmaster.internal/ubuntu noble-updates/main armhf udev armhf 255.4-1ubuntu8.2 [1852 kB] 132s Get:11 http://ftpmaster.internal/ubuntu noble-updates/main armhf libudev1 armhf 255.4-1ubuntu8.2 [167 kB] 132s Get:12 http://ftpmaster.internal/ubuntu noble-updates/main armhf libapparmor1 armhf 4.0.1really4.0.0-beta3-0ubuntu0.1 [45.8 kB] 132s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 33.2~24.04 [19.3 kB] 132s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 33.2~24.04 [233 kB] 132s Get:15 http://ftpmaster.internal/ubuntu noble-proposed/main armhf xkb-data all 2.41-2ubuntu1.1 [397 kB] 132s Get:16 http://ftpmaster.internal/ubuntu noble-updates/main armhf apparmor armhf 4.0.1really4.0.0-beta3-0ubuntu0.1 [563 kB] 136s Preconfiguring packages ... 137s Fetched 10.0 MB in 4s (2632 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 ... 57911 files and directories currently installed.) 137s Preparing to unpack .../0-libnss-systemd_255.4-1ubuntu8.2_armhf.deb ... 137s Unpacking libnss-systemd:armhf (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 138s Preparing to unpack .../1-systemd-dev_255.4-1ubuntu8.2_all.deb ... 138s Unpacking systemd-dev (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 139s Preparing to unpack .../2-systemd-timesyncd_255.4-1ubuntu8.2_armhf.deb ... 139s Unpacking systemd-timesyncd (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 140s Preparing to unpack .../3-systemd-resolved_255.4-1ubuntu8.2_armhf.deb ... 140s Unpacking systemd-resolved (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 140s Preparing to unpack .../4-libsystemd-shared_255.4-1ubuntu8.2_armhf.deb ... 140s Unpacking libsystemd-shared:armhf (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 141s Preparing to unpack .../5-libsystemd0_255.4-1ubuntu8.2_armhf.deb ... 141s Unpacking libsystemd0:armhf (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 142s Setting up libsystemd0:armhf (255.4-1ubuntu8.2) ... 142s (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 ... 57911 files and directories currently installed.) 142s Preparing to unpack .../systemd-sysv_255.4-1ubuntu8.2_armhf.deb ... 142s Unpacking systemd-sysv (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 143s Preparing to unpack .../libpam-systemd_255.4-1ubuntu8.2_armhf.deb ... 143s Unpacking libpam-systemd:armhf (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 143s Preparing to unpack .../systemd_255.4-1ubuntu8.2_armhf.deb ... 144s Unpacking systemd (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 144s Preparing to unpack .../udev_255.4-1ubuntu8.2_armhf.deb ... 145s Unpacking udev (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 146s Preparing to unpack .../libudev1_255.4-1ubuntu8.2_armhf.deb ... 146s Unpacking libudev1:armhf (255.4-1ubuntu8.2) over (255.4-1ubuntu8.1) ... 146s Setting up libudev1:armhf (255.4-1ubuntu8.2) ... 146s (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 ... 57912 files and directories currently installed.) 146s Preparing to unpack .../libapparmor1_4.0.1really4.0.0-beta3-0ubuntu0.1_armhf.deb ... 146s Unpacking libapparmor1:armhf (4.0.1really4.0.0-beta3-0ubuntu0.1) over (4.0.1-0ubuntu0.24.04.2) ... 147s Preparing to unpack .../ubuntu-pro-client-l10n_33.2~24.04_armhf.deb ... 147s Unpacking ubuntu-pro-client-l10n (33.2~24.04) over (32.3.1~24.04) ... 148s Preparing to unpack .../ubuntu-pro-client_33.2~24.04_armhf.deb ... 149s Unpacking ubuntu-pro-client (33.2~24.04) over (32.3.1~24.04) ... 151s Preparing to unpack .../xkb-data_2.41-2ubuntu1.1_all.deb ... 151s Unpacking xkb-data (2.41-2ubuntu1.1) over (2.41-2ubuntu1) ... 152s Preparing to unpack .../apparmor_4.0.1really4.0.0-beta3-0ubuntu0.1_armhf.deb ... 189s Unpacking apparmor (4.0.1really4.0.0-beta3-0ubuntu0.1) over (4.0.1-0ubuntu0.24.04.2) ... 198s Setting up libapparmor1:armhf (4.0.1really4.0.0-beta3-0ubuntu0.1) ... 198s Setting up xkb-data (2.41-2ubuntu1.1) ... 198s Setting up systemd-dev (255.4-1ubuntu8.2) ... 198s Setting up apparmor (4.0.1really4.0.0-beta3-0ubuntu0.1) ... 198s Installing new version of config file /etc/apparmor.d/abstractions/authentication ... 198s Installing new version of config file /etc/apparmor.d/abstractions/samba ... 198s Installing new version of config file /etc/apparmor.d/firefox ... 206s apparmor_parser: Unable to replace "lsb_release". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 206s 207s apparmor_parser: Unable to replace "kmod". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 207s 207s apparmor_parser: Unable to replace "nvidia_modprobe". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 207s 217s Removing obsolete conffile /etc/apparmor.d/abstractions/transmission-common ... 217s Removing obsolete conffile /etc/apparmor.d/balena-etcher ... 217s Removing obsolete conffile /etc/apparmor.d/bwrap-userns-restrict ... 217s Removing obsolete conffile /etc/apparmor.d/foliate ... 218s Removing obsolete conffile /etc/apparmor.d/transmission ... 218s Removing obsolete conffile /etc/apparmor.d/wike ... 228s Reloading AppArmor profiles 228s /sbin/apparmor_parser: Unable to replace "Discord". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "1password". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "MongoDB Compass". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "busybox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "QtWebEngineProcess". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "brave". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "buildah". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "cam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "ch-checkns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "chrome". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "ch-run". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "vscode". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "epiphany". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "crun". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "devhelp". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "element-desktop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "flatpak". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "evolution". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "firefox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "geary". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "goldendict". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "github-desktop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "ipa_verify". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "kchmviewer". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "libcamerify". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "keybase". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lc-compliance". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "linux-sandbox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "loupe". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-attach". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-destroy". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-create". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-stop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-execute". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-unshare". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lxc-usernsexec". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "mmdebstrap". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "msedge". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "lsb_release". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "nautilus". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "notepadqq". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "opera". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "obsidian". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "opam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "podman". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "pageedit". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "qmapshack". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "privacybrowser". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "polypane". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "qcam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "rootlesskit". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "kmod". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "nvidia_modprobe". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "qutebrowser". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "rssguard". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "rpm". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "QtWebEngineProcess". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "plasmashell". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "runc". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-clean". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-abort". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-distupgrade". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-adduser". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-apt". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-createchroot". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-unhold". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-hold". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-destroychroot". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-upgrade". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-checkpackages". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-shell". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "signal-desktop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "sbuild-update". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "steam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "scide". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "slack". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "slirp4netns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "toybox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "trinity". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "thunderbird". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "stress-ng". /sbin/apparmor_parser: Unable to replace "surfshark". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "tuxedo-control-center". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "systemd-coredump". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "tup". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "unprivileged_userns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "userbindmount". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "uwsgi-core". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "vdens". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "/usr/bin/man". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "virtiofsd". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "vivaldi-bin". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "ubuntu_pro_apt_news". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "unix-chkpwd". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "rsyslogd". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "vpnns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 228s /sbin/apparmor_parser: Unable to replace "wpcom". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 228s 229s /sbin/apparmor_parser: Unable to replace "tcpdump". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 229s 229s /sbin/apparmor_parser: Unable to replace "apt_methods". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 229s 229s /sbin/apparmor_parser: Unable to replace "ubuntu_pro_esm_cache". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 229s 229s Error: At least one profile failed to load 229s Setting up libsystemd-shared:armhf (255.4-1ubuntu8.2) ... 229s Setting up ubuntu-pro-client (33.2~24.04) ... 229s Installing new version of config file /etc/apparmor.d/ubuntu_pro_apt_news ... 230s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 230s 231s apparmor_parser: Unable to replace "apt_methods". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 231s 231s apparmor_parser: Unable to replace "ubuntu_pro_esm_cache". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 231s 240s Setting up ubuntu-pro-client-l10n (33.2~24.04) ... 240s Setting up systemd (255.4-1ubuntu8.2) ... 249s Setting up systemd-timesyncd (255.4-1ubuntu8.2) ... 251s Setting up udev (255.4-1ubuntu8.2) ... 256s Setting up systemd-resolved (255.4-1ubuntu8.2) ... 262s Setting up systemd-sysv (255.4-1ubuntu8.2) ... 262s Setting up libnss-systemd:armhf (255.4-1ubuntu8.2) ... 262s Setting up libpam-systemd:armhf (255.4-1ubuntu8.2) ... 266s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 268s Processing triggers for man-db (2.12.0-4build2) ... 274s Processing triggers for dbus (1.14.10-4ubuntu4) ... 276s Processing triggers for initramfs-tools (0.142ubuntu25.1) ... 287s Reading package lists... 287s Building dependency tree... 287s Reading state information... 289s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 295s autopkgtest [07:34:43]: rebooting testbed after setup commands that affected boot 378s autopkgtest [07:36:06]: testbed running kernel: Linux 6.5.0-35-generic #35~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue May 7 11:19:33 UTC 2 410s autopkgtest [07:36:38]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 431s Get:1 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (dsc) [1420 B] 431s Get:2 http://ftpmaster.internal/ubuntu noble/main wsl-pro-service 0.1.4 (tar) [1762 kB] 432s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 432s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 432s gpgv: Can't check signature: No public key 432s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 436s autopkgtest [07:37:04]: testing package wsl-pro-service version 0.1.4 439s autopkgtest [07:37:07]: build not needed 443s autopkgtest [07:37:11]: test command1: preparing testbed 457s Reading package lists... 458s Building dependency tree... 458s Reading state information... 458s Starting pkgProblemResolver with broken count: 0 458s Starting 2 pkgProblemResolver with broken count: 0 458s Done 459s The following additional packages will be installed: 459s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 459s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 459s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 459s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 459s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 459s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 459s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 459s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 459s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 459s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 459s rpcsvc-proto 459s Suggested packages: 459s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 459s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 459s gcc-doc gdb-arm-linux-gnueabihf gettext-doc libasprintf-dev libgettextpo-dev 459s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 459s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 459s Recommended packages: 459s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 459s libltdl-dev libmail-sendmail-perl 459s The following NEW packages will be installed: 459s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 459s cpp cpp-13 cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper 459s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 459s g++-13 g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 459s gcc-13-arm-linux-gnueabihf gcc-13-base gcc-arm-linux-gnueabihf gettext 459s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 459s libarchive-zip-perl libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 459s libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl 459s libgcc-13-dev libgomp1 libisl23 libmpc3 libstdc++-13-dev 459s libsub-override-perl libtool libubsan1 linux-libc-dev m4 po-debconf 459s rpcsvc-proto 460s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 460s Need to get 95.3 MB/95.3 MB of archives. 460s After this operation, 377 MB of additional disk space will be used. 460s Get:1 /tmp/autopkgtest.omIHT6/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [760 B] 460s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf m4 armhf 1.4.19-4build1 [235 kB] 460s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf autoconf all 2.71-3 [339 kB] 460s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf autotools-dev all 20220109.1 [44.9 kB] 460s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf automake all 1:1.16.5-1.3ubuntu1 [558 kB] 461s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf autopoint all 0.21-14ubuntu2 [422 kB] 461s Get:7 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc-dev-bin armhf 2.39-0ubuntu8.2 [19.1 kB] 461s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main armhf linux-libc-dev armhf 6.8.0-38.38 [1604 kB] 461s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf libcrypt-dev armhf 1:4.4.36-4build1 [120 kB] 461s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf rpcsvc-proto armhf 1.4.2-0ubuntu7 [62.2 kB] 461s Get:11 http://ftpmaster.internal/ubuntu noble-updates/main armhf libc6-dev armhf 2.39-0ubuntu8.2 [1351 kB] 461s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-base armhf 13.2.0-23ubuntu4 [48.9 kB] 461s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libisl23 armhf 0.26-3build1 [544 kB] 461s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf libmpc3 armhf 1.3.1-1build1 [46.7 kB] 461s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [8759 kB] 462s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-23ubuntu4 [1034 B] 462s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 462s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 462s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240412-0ubuntu1 [39.0 kB] 462s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240412-0ubuntu1 [125 kB] 462s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240412-0ubuntu1 [7816 B] 462s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240412-0ubuntu1 [2942 kB] 462s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240412-0ubuntu1 [1154 kB] 462s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-23ubuntu4 [899 kB] 462s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [16.9 MB] 463s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13 armhf 13.2.0-23ubuntu4 [453 kB] 463s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf gcc-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [1220 B] 463s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf gcc armhf 4:13.2.0-7ubuntu1 [5022 B] 463s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf libstdc++-13-dev armhf 13.2.0-23ubuntu4 [2456 kB] 463s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf g++-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu4 [9941 kB] 464s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf g++-13 armhf 13.2.0-23ubuntu4 [14.5 kB] 464s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf g++-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [966 B] 464s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf g++ armhf 4:13.2.0-7ubuntu1 [1090 B] 464s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf build-essential armhf 12.10ubuntu1 [4928 B] 464s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 464s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf libtool all 2.4.7-7build1 [166 kB] 464s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf dh-autoreconf all 20 [16.1 kB] 464s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 464s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libsub-override-perl all 0.10-1 [10.0 kB] 464s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 464s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf dh-strip-nondeterminism all 1.13.1-1 [5362 B] 464s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf libdw1t64 armhf 0.190-1.1build4 [235 kB] 464s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5build2 [42.3 kB] 464s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1build6 [116 kB] 464s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu2 [800 kB] 464s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 464s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 464s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.14.1ubuntu5 [869 kB] 464s Get:49 http://ftpmaster.internal/ubuntu noble-updates/universe armhf dh-apport all 2.28.1-0ubuntu3 [17.2 kB] 464s Get:50 http://ftpmaster.internal/ubuntu noble-updates/main armhf golang-1.22-src all 1.22.2-2ubuntu0.1 [19.7 MB] 465s Get:51 http://ftpmaster.internal/ubuntu noble-updates/main armhf golang-1.22-go armhf 1.22.2-2ubuntu0.1 [23.7 MB] 466s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.22~2build1 [5078 B] 466s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.22~2build1 [43.9 kB] 466s Get:54 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 471s Fetched 95.3 MB in 7s (14.6 MB/s) 471s Selecting previously unselected package m4. 471s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 57907 files and directories currently installed.) 471s Preparing to unpack .../00-m4_1.4.19-4build1_armhf.deb ... 471s Unpacking m4 (1.4.19-4build1) ... 472s Selecting previously unselected package autoconf. 473s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 473s Unpacking autoconf (2.71-3) ... 473s Selecting previously unselected package autotools-dev. 474s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 474s Unpacking autotools-dev (20220109.1) ... 474s Selecting previously unselected package automake. 475s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 475s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 475s Selecting previously unselected package autopoint. 476s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 476s Unpacking autopoint (0.21-14ubuntu2) ... 476s Selecting previously unselected package libc-dev-bin. 476s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu8.2_armhf.deb ... 476s Unpacking libc-dev-bin (2.39-0ubuntu8.2) ... 476s Selecting previously unselected package linux-libc-dev:armhf. 477s Preparing to unpack .../06-linux-libc-dev_6.8.0-38.38_armhf.deb ... 477s Unpacking linux-libc-dev:armhf (6.8.0-38.38) ... 477s Selecting previously unselected package libcrypt-dev:armhf. 478s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4build1_armhf.deb ... 478s Unpacking libcrypt-dev:armhf (1:4.4.36-4build1) ... 478s Selecting previously unselected package rpcsvc-proto. 480s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu7_armhf.deb ... 480s Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ... 480s Selecting previously unselected package libc6-dev:armhf. 481s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu8.2_armhf.deb ... 481s Unpacking libc6-dev:armhf (2.39-0ubuntu8.2) ... 482s Selecting previously unselected package gcc-13-base:armhf. 483s Preparing to unpack .../10-gcc-13-base_13.2.0-23ubuntu4_armhf.deb ... 483s Unpacking gcc-13-base:armhf (13.2.0-23ubuntu4) ... 483s Selecting previously unselected package libisl23:armhf. 484s Preparing to unpack .../11-libisl23_0.26-3build1_armhf.deb ... 484s Unpacking libisl23:armhf (0.26-3build1) ... 484s Selecting previously unselected package libmpc3:armhf. 485s Preparing to unpack .../12-libmpc3_1.3.1-1build1_armhf.deb ... 485s Unpacking libmpc3:armhf (1.3.1-1build1) ... 485s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 486s Preparing to unpack .../13-cpp-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 486s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 487s Selecting previously unselected package cpp-13. 488s Preparing to unpack .../14-cpp-13_13.2.0-23ubuntu4_armhf.deb ... 488s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 488s Selecting previously unselected package cpp-arm-linux-gnueabihf. 489s Preparing to unpack .../15-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 489s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 489s Selecting previously unselected package cpp. 490s Preparing to unpack .../16-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 490s Unpacking cpp (4:13.2.0-7ubuntu1) ... 490s Selecting previously unselected package libcc1-0:armhf. 491s Preparing to unpack .../17-libcc1-0_14-20240412-0ubuntu1_armhf.deb ... 491s Unpacking libcc1-0:armhf (14-20240412-0ubuntu1) ... 492s Selecting previously unselected package libgomp1:armhf. 492s Preparing to unpack .../18-libgomp1_14-20240412-0ubuntu1_armhf.deb ... 492s Unpacking libgomp1:armhf (14-20240412-0ubuntu1) ... 493s Selecting previously unselected package libatomic1:armhf. 494s Preparing to unpack .../19-libatomic1_14-20240412-0ubuntu1_armhf.deb ... 494s Unpacking libatomic1:armhf (14-20240412-0ubuntu1) ... 494s Selecting previously unselected package libasan8:armhf. 495s Preparing to unpack .../20-libasan8_14-20240412-0ubuntu1_armhf.deb ... 495s Unpacking libasan8:armhf (14-20240412-0ubuntu1) ... 496s Selecting previously unselected package libubsan1:armhf. 497s Preparing to unpack .../21-libubsan1_14-20240412-0ubuntu1_armhf.deb ... 497s Unpacking libubsan1:armhf (14-20240412-0ubuntu1) ... 497s Selecting previously unselected package libgcc-13-dev:armhf. 498s Preparing to unpack .../22-libgcc-13-dev_13.2.0-23ubuntu4_armhf.deb ... 498s Unpacking libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 498s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 499s Preparing to unpack .../23-gcc-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 499s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 500s Selecting previously unselected package gcc-13. 502s Preparing to unpack .../24-gcc-13_13.2.0-23ubuntu4_armhf.deb ... 502s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 502s Selecting previously unselected package gcc-arm-linux-gnueabihf. 503s Preparing to unpack .../25-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 503s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 503s Selecting previously unselected package gcc. 504s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 504s Unpacking gcc (4:13.2.0-7ubuntu1) ... 504s Selecting previously unselected package libstdc++-13-dev:armhf. 505s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-23ubuntu4_armhf.deb ... 505s Unpacking libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 506s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 507s Preparing to unpack .../28-g++-13-arm-linux-gnueabihf_13.2.0-23ubuntu4_armhf.deb ... 507s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 507s Selecting previously unselected package g++-13. 509s Preparing to unpack .../29-g++-13_13.2.0-23ubuntu4_armhf.deb ... 510s Unpacking g++-13 (13.2.0-23ubuntu4) ... 511s Selecting previously unselected package g++-arm-linux-gnueabihf. 512s Preparing to unpack .../30-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 512s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 513s Selecting previously unselected package g++. 514s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 515s Unpacking g++ (4:13.2.0-7ubuntu1) ... 516s Selecting previously unselected package build-essential. 517s Preparing to unpack .../32-build-essential_12.10ubuntu1_armhf.deb ... 517s Unpacking build-essential (12.10ubuntu1) ... 518s Selecting previously unselected package libdebhelper-perl. 519s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 519s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 520s Selecting previously unselected package libtool. 520s Preparing to unpack .../34-libtool_2.4.7-7build1_all.deb ... 520s Unpacking libtool (2.4.7-7build1) ... 521s Selecting previously unselected package dh-autoreconf. 521s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 521s Unpacking dh-autoreconf (20) ... 521s Selecting previously unselected package libarchive-zip-perl. 522s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 522s Unpacking libarchive-zip-perl (1.68-1) ... 522s Selecting previously unselected package libsub-override-perl. 522s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 522s Unpacking libsub-override-perl (0.10-1) ... 523s Selecting previously unselected package libfile-stripnondeterminism-perl. 524s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 524s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 524s Selecting previously unselected package dh-strip-nondeterminism. 526s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 526s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 526s Selecting previously unselected package libdw1t64:armhf. 528s Preparing to unpack .../40-libdw1t64_0.190-1.1build4_armhf.deb ... 528s Unpacking libdw1t64:armhf (0.190-1.1build4) ... 529s Selecting previously unselected package debugedit. 531s Preparing to unpack .../41-debugedit_1%3a5.0-5build2_armhf.deb ... 532s Unpacking debugedit (1:5.0-5build2) ... 533s Selecting previously unselected package dwz. 535s Preparing to unpack .../42-dwz_0.15-1build6_armhf.deb ... 536s Unpacking dwz (0.15-1build6) ... 536s Selecting previously unselected package gettext. 538s Preparing to unpack .../43-gettext_0.21-14ubuntu2_armhf.deb ... 538s Unpacking gettext (0.21-14ubuntu2) ... 544s Selecting previously unselected package intltool-debian. 547s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 548s Unpacking intltool-debian (0.35.0+20060710.6) ... 552s Selecting previously unselected package po-debconf. 554s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 554s Unpacking po-debconf (1.0.21+nmu1) ... 556s Selecting previously unselected package debhelper. 556s Preparing to unpack .../46-debhelper_13.14.1ubuntu5_all.deb ... 556s Unpacking debhelper (13.14.1ubuntu5) ... 557s Selecting previously unselected package dh-apport. 558s Preparing to unpack .../47-dh-apport_2.28.1-0ubuntu3_all.deb ... 558s Unpacking dh-apport (2.28.1-0ubuntu3) ... 559s Selecting previously unselected package golang-1.22-src. 561s Preparing to unpack .../48-golang-1.22-src_1.22.2-2ubuntu0.1_all.deb ... 561s Unpacking golang-1.22-src (1.22.2-2ubuntu0.1) ... 581s Selecting previously unselected package golang-1.22-go. 582s Preparing to unpack .../49-golang-1.22-go_1.22.2-2ubuntu0.1_armhf.deb ... 582s Unpacking golang-1.22-go (1.22.2-2ubuntu0.1) ... 583s Selecting previously unselected package golang-src. 585s Preparing to unpack .../50-golang-src_2%3a1.22~2build1_all.deb ... 585s Unpacking golang-src (2:1.22~2build1) ... 585s Selecting previously unselected package golang-go:armhf. 587s Preparing to unpack .../51-golang-go_2%3a1.22~2build1_armhf.deb ... 587s Unpacking golang-go:armhf (2:1.22~2build1) ... 587s Selecting previously unselected package dh-golang. 588s Preparing to unpack .../52-dh-golang_1.62_all.deb ... 588s Unpacking dh-golang (1.62) ... 589s Selecting previously unselected package autopkgtest-satdep. 589s Preparing to unpack .../53-1-autopkgtest-satdep.deb ... 589s Unpacking autopkgtest-satdep (0) ... 590s Setting up libarchive-zip-perl (1.68-1) ... 590s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 590s Setting up linux-libc-dev:armhf (6.8.0-38.38) ... 590s Setting up m4 (1.4.19-4build1) ... 590s Setting up libgomp1:armhf (14-20240412-0ubuntu1) ... 590s Setting up dh-apport (2.28.1-0ubuntu3) ... 590s Setting up libdw1t64:armhf (0.190-1.1build4) ... 590s Setting up autotools-dev (20220109.1) ... 590s Setting up rpcsvc-proto (1.4.2-0ubuntu7) ... 590s Setting up gcc-13-base:armhf (13.2.0-23ubuntu4) ... 590s Setting up golang-1.22-src (1.22.2-2ubuntu0.1) ... 590s Setting up libmpc3:armhf (1.3.1-1build1) ... 590s Setting up libatomic1:armhf (14-20240412-0ubuntu1) ... 590s Setting up autopoint (0.21-14ubuntu2) ... 590s Setting up autoconf (2.71-3) ... 590s Setting up libubsan1:armhf (14-20240412-0ubuntu1) ... 590s Setting up dwz (0.15-1build6) ... 590s Setting up libcrypt-dev:armhf (1:4.4.36-4build1) ... 590s Setting up libasan8:armhf (14-20240412-0ubuntu1) ... 590s Setting up debugedit (1:5.0-5build2) ... 590s Setting up libsub-override-perl (0.10-1) ... 590s Setting up libisl23:armhf (0.26-3build1) ... 590s Setting up libc-dev-bin (2.39-0ubuntu8.2) ... 590s Setting up golang-src (2:1.22~2build1) ... 590s Setting up libcc1-0:armhf (14-20240412-0ubuntu1) ... 590s Setting up automake (1:1.16.5-1.3ubuntu1) ... 590s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 590s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 590s Setting up gettext (0.21-14ubuntu2) ... 590s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 590s Setting up golang-1.22-go (1.22.2-2ubuntu0.1) ... 590s Setting up intltool-debian (0.35.0+20060710.6) ... 590s Setting up dh-strip-nondeterminism (1.13.1-1) ... 590s Setting up libgcc-13-dev:armhf (13.2.0-23ubuntu4) ... 590s Setting up libc6-dev:armhf (2.39-0ubuntu8.2) ... 590s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 590s Setting up libstdc++-13-dev:armhf (13.2.0-23ubuntu4) ... 590s Setting up cpp-13 (13.2.0-23ubuntu4) ... 590s Setting up golang-go:armhf (2:1.22~2build1) ... 590s Setting up po-debconf (1.0.21+nmu1) ... 590s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 590s Setting up gcc-13 (13.2.0-23ubuntu4) ... 590s Setting up cpp (4:13.2.0-7ubuntu1) ... 590s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 590s Setting up g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu4) ... 590s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 590s Setting up g++-13 (13.2.0-23ubuntu4) ... 590s Setting up libtool (2.4.7-7build1) ... 590s Setting up gcc (4:13.2.0-7ubuntu1) ... 590s Setting up dh-autoreconf (20) ... 590s Setting up g++ (4:13.2.0-7ubuntu1) ... 590s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 590s Setting up build-essential (12.10ubuntu1) ... 590s Setting up debhelper (13.14.1ubuntu5) ... 590s Setting up dh-golang (1.62) ... 590s Setting up autopkgtest-satdep (0) ... 590s Processing triggers for man-db (2.12.0-4build2) ... 596s Processing triggers for install-info (7.1-3build2) ... 598s Processing triggers for libc-bin (2.39-0ubuntu8.2) ... 619s (Reading database ... 76047 files and directories currently installed.) 619s Removing autopkgtest-satdep (0) ... 629s autopkgtest [07:40:17]: test command1: ./debian/tests/test 629s autopkgtest [07:40:17]: test command1: [----------------------- 757s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 757s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 768s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 777s === RUN TestRunSignal 777s === RUN TestRunSignal/Send_SIGINT_exits 777s INFO Starting WSL Pro Service version Dev 777s === RUN TestRunSignal/Send_SIGTERM_exits 777s INFO Starting WSL Pro Service version Dev 777s --- PASS: TestRunSignal (0.77s) 777s --- PASS: TestRunSignal/Send_SIGINT_exits (0.58s) 777s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.19s) 777s === RUN TestRun 777s === PAUSE TestRun 777s === CONT TestRun 777s === RUN TestRun/Run_and_return_error 777s === PAUSE TestRun/Run_and_return_error 777s === RUN TestRun/Run_and_return_usage_error 777s === PAUSE TestRun/Run_and_return_usage_error 777s === RUN TestRun/Run_and_usage_error_only_does_not_fail 777s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 777s === RUN TestRun/Run_and_exit_successfully 777s === PAUSE TestRun/Run_and_exit_successfully 777s === CONT TestRun/Run_and_return_error 777s === CONT TestRun/Run_and_usage_error_only_does_not_fail 777s === CONT TestRun/Run_and_exit_successfully 777s === CONT TestRun/Run_and_return_usage_error 777s INFO Starting WSL Pro Service version Dev 777s INFO Starting WSL Pro Service version Dev 777s INFO Starting WSL Pro Service version Dev 777s INFO Starting WSL Pro Service version Dev 777s ERROR context.Background Error requested 777s ERROR context.Background Error requested 777s --- PASS: TestRun (0.00s) 777s --- PASS: TestRun/Run_and_return_error (0.11s) 777s --- PASS: TestRun/Run_and_return_usage_error (0.13s) 777s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.13s) 777s --- PASS: TestRun/Run_and_exit_successfully (0.16s) 777s PASS 777s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 20.248s 1061s === RUN TestHelp 1061s --- PASS: TestHelp (0.92s) 1061s === RUN TestCompletion 1061s --- PASS: TestCompletion (0.23s) 1061s === RUN TestVersion 1061s --- PASS: TestVersion (0.00s) 1061s === RUN TestNoUsageError 1061s --- PASS: TestNoUsageError (0.00s) 1061s === RUN TestUsageError 1061s === PAUSE TestUsageError 1061s === RUN TestCanQuitWhenExecute 1061s === PAUSE TestCanQuitWhenExecute 1061s === RUN TestCanQuitTwice 1061s === PAUSE TestCanQuitTwice 1061s === RUN TestAppCanQuitWithoutExecute 1061s === PAUSE TestAppCanQuitWithoutExecute 1061s === RUN TestAppRunFailsOnComponentsCreationAndQuit 1061s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 1061s === RUN TestAppGetRootCmd 1061s === PAUSE TestAppGetRootCmd 1061s === RUN TestWithProMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithProMock (0.00s) 1061s === RUN TestWithWslPathMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithWslPathMock (0.00s) 1061s === RUN TestWithWslInfoMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithWslInfoMock (0.00s) 1061s === RUN TestWithCmdExeMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithCmdExeMock (0.00s) 1061s === CONT TestUsageError 1061s === CONT TestAppCanQuitWithoutExecute 1061s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 1061s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 1061s === CONT TestAppGetRootCmd 1061s --- PASS: TestAppGetRootCmd (0.00s) 1061s === CONT TestAppRunFailsOnComponentsCreationAndQuit 1061s === CONT TestCanQuitTwice 1061s --- PASS: TestUsageError (0.04s) 1061s === CONT TestCanQuitWhenExecute 1061s time="2024-07-19T07:42:40Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1061s time="2024-07-19T07:42:40Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:75 New() Building new daemon" 1061s time="2024-07-19T07:42:40Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43407" 1061s time="2024-07-19T07:42:40Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 1061s time="2024-07-19T07:42:40Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:75 New() Building new daemon" 1061s time="2024-07-19T07:45:07Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1061s time="2024-07-19T07:45:28Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1061s time="2024-07-19T07:45:28Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:45:28Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:45:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 1061s time="2024-07-19T07:45:30Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:45:30Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:45:39Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:45:39Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:45:39Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (185.04s) 1061s time="2024-07-19T07:46:41Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:38941" 1061s time="2024-07-19T07:46:45Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1061s time="2024-07-19T07:46:46Z" level=warning msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:184 (*Daemon).Serve.func1() Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:38941: connect: connection refused\"" 1061s time="2024-07-19T07:46:46Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1061s time="2024-07-19T07:46:46Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s time="2024-07-19T07:46:46Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:46:46Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:46:46Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s --- PASS: TestCanQuitWhenExecute (246.17s) 1061s time="2024-07-19T07:46:47Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:42527" 1061s time="2024-07-19T07:46:47Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 1061s time="2024-07-19T07:46:48Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 1061s time="2024-07-19T07:46:48Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 1061s time="2024-07-19T07:47:29Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/streams/server.go:141 (*Server).Serve() Server: sent preface messages to all streams" 1061s time="2024-07-19T07:47:29Z" level=warning msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:186 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 1061s time="2024-07-19T07:47:29Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:47:29Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 1061s time="2024-07-19T07:47:29Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.omIHT6/build.3ed/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 1061s time="2024-07-19T07:47:29Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.omIHT6/build.3ed/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 1061s time="2024-07-19T07:47:29Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.omIHT6/build.3ed/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 1061s time="2024-07-19T07:47:29Z" level=info msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 1061s time="2024-07-19T07:47:29Z" level=debug msg="/tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 1061s --- PASS: TestCanQuitTwice (289.18s) 1061s PASS 1061s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 303.860s 1061s === RUN TestApplyProToken 1061s === PAUSE TestApplyProToken 1061s === RUN TestApplyLandscapeConfig 1061s === PAUSE TestApplyLandscapeConfig 1061s === RUN TestWithProMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithProMock (0.44s) 1061s === RUN TestWithLandscapeConfigMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1061s === RUN TestWithWslPathMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithWslPathMock (0.00s) 1061s === RUN TestWithWslInfoMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithWslInfoMock (0.00s) 1061s === RUN TestWithCmdExeMock 1061s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1061s --- SKIP: TestWithCmdExeMock (0.00s) 1061s === CONT TestApplyProToken 1061s === RUN TestApplyProToken/Success_attaching 1061s === PAUSE TestApplyProToken/Success_attaching 1061s === RUN TestApplyProToken/Success_detaching 1061s === PAUSE TestApplyProToken/Success_detaching 1061s === RUN TestApplyProToken/Error_calling_pro_detach 1061s === PAUSE TestApplyProToken/Error_calling_pro_detach 1061s === RUN TestApplyProToken/Error_calling_pro_attach 1061s === PAUSE TestApplyProToken/Error_calling_pro_attach 1061s === CONT TestApplyProToken/Success_attaching 1061s === CONT TestApplyProToken/Error_calling_pro_attach 1061s === CONT TestApplyLandscapeConfig 1061s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 1061s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 1061s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 1061s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 1061s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 1061s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 1061s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 1061s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 1061s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 1061s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 1061s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 1061s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 1061s === CONT TestApplyProToken/Error_calling_pro_detach 1061s === CONT TestApplyProToken/Success_detaching 1061s time="2024-07-19T07:42:50Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyProToken: Received empty token: detaching" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyLandscapeConfig: received config: registering" 1061s time="2024-07-19T07:42:51Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 1061s time="2024-07-19T07:42:52Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 1061s --- PASS: TestApplyLandscapeConfig (0.00s) 1061s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (105.88s) 1061s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (111.63s) 1061s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (119.33s) 1061s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (129.27s) 1061s --- PASS: TestApplyProToken (0.00s) 1061s --- PASS: TestApplyProToken/Success_detaching (113.41s) 1061s --- PASS: TestApplyProToken/Error_calling_pro_detach (117.91s) 1061s --- PASS: TestApplyProToken/Success_attaching (140.39s) 1061s --- PASS: TestApplyProToken/Error_calling_pro_attach (151.03s) 1061s PASS 1061s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 175.243s 1219s === RUN TestNew 1219s === PAUSE TestNew 1219s === RUN TestServe 1219s === PAUSE TestServe 1219s === RUN TestServeAndQuit 1219s === PAUSE TestServeAndQuit 1219s === RUN TestReconnection 1219s === PAUSE TestReconnection 1219s === RUN TestWithProMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithProMock (0.00s) 1219s === RUN TestWithWslPathMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslPathMock (0.00s) 1219s === RUN TestWithWslInfoMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslInfoMock (0.00s) 1219s === RUN TestWithCmdExeMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithCmdExeMock (0.00s) 1219s === CONT TestNew 1219s === RUN TestNew/Success 1219s === PAUSE TestNew/Success 1219s === RUN TestNew/Error_when_WslPath_returns_error 1219s === PAUSE TestNew/Error_when_WslPath_returns_error 1219s === CONT TestNew/Success 1219s === CONT TestServeAndQuit 1219s === RUN TestServeAndQuit/Success_with_graceful_quit 1219s === PAUSE TestServeAndQuit/Success_with_graceful_quit 1219s === RUN TestServeAndQuit/Success_with_forceful_quit 1219s === PAUSE TestServeAndQuit/Success_with_forceful_quit 1219s === RUN TestServeAndQuit/Success_with_double_quit 1219s === PAUSE TestServeAndQuit/Success_with_double_quit 1219s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 1219s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 1219s === CONT TestServeAndQuit/Success_with_graceful_quit 1219s === CONT TestServe 1219s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 1219s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 1219s === RUN TestServe/No_connection_because_there_is_no_server 1219s === PAUSE TestServe/No_connection_because_there_is_no_server 1219s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1219s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1219s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 1219s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 1219s === RUN TestServe/Success_with_systemd_notifier_returning_true 1219s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 1219s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 1219s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 1219s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 1219s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 1219s === RUN TestServe/No_connection_because_there_are_no_certificates 1219s === PAUSE TestServe/No_connection_because_there_are_no_certificates 1219s === RUN TestServe/Error_because_the_context_is_pre-cancelled 1219s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 1219s === RUN TestServe/Error_because_the_notifier_returns_an_error 1219s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 1219s === RUN TestServe/Success 1219s === PAUSE TestServe/Success 1219s === RUN TestServe/No_connection_because_the_port_file_is_empty 1219s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 1219s === RUN TestServe/No_connection_because_the_port_file_has_port_0 1219s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 1219s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 1219s time="2024-07-19T07:42:40Z" level=debug msg="Building new daemon" 1219s === CONT TestServe/No_connection_because_there_are_no_certificates 1219s === CONT TestServe/Success_with_systemd_notifier_returning_true 1219s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 1219s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 1219s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 1219s time="2024-07-19T07:42:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44429" 1219s time="2024-07-19T07:42:40Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38439" 1219s time="2024-07-19T07:42:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37807" 1219s time="2024-07-19T07:42:40Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46107" 1219s time="2024-07-19T07:42:41Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:41Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40015" 1219s time="2024-07-19T07:42:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38341" 1219s time="2024-07-19T07:42:41Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:41Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:42:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37123" 1219s time="2024-07-19T07:42:41Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:44:59Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:44:59Z" 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_exist3105708643/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist3105708643/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1219s time="2024-07-19T07:45:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:45:00Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:00Z" 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" 1219s time="2024-07-19T07:45:00Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s === CONT TestReconnection 1219s === RUN TestReconnection/Success_connecting_after_failing_to_connect 1219s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 1219s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 1219s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 1219s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1219s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1219s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1219s time="2024-07-19T07:45:01Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:01Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:01Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:01Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:01Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:45:01Z" level=debug msg="All connections have now ended." 1219s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 1219s time="2024-07-19T07:45:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40257" 1219s time="2024-07-19T07:45:01Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38029" 1219s time="2024-07-19T07:45:01Z" level=debug msg="Building new daemon" 1219s === CONT TestServeAndQuit/Success_with_double_quit 1219s time="2024-07-19T07:45:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44335" 1219s time="2024-07-19T07:45:01Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:06Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:06Z" 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" 1219s time="2024-07-19T07:45:06Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:45:07Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:07Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:07Z" level=debug msg="All connections have now ended." 1219s === CONT TestServe/No_connection_because_the_port_file_is_empty 1219s time="2024-07-19T07:45:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40887" 1219s time="2024-07-19T07:45:07Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:08Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:08Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:08Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:08Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:08Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s === CONT TestNew/Error_when_WslPath_returns_error 1219s time="2024-07-19T07:45:09Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:10Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:10Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:45:10Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:10Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:45:20Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:45:20Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:20Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:45:39Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38341" 1219s time="2024-07-19T07:45:39Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:45:39Z" level=debug msg="Updated systemd status to \"Connected\"" 1219s time="2024-07-19T07:45:39Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37123" 1219s time="2024-07-19T07:45:39Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates1171676483/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 1219s time="2024-07-19T07:45:39Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:45:39Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:45:39Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:45:39Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:45:39Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:45:39Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s === NAME TestServeAndQuit/Success_with_graceful_quit 1219s daemon_test.go:290: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:290 1219s Error: Condition never satisfied 1219s Test: TestServeAndQuit/Success_with_graceful_quit 1219s Messages: Systemd state should have been set to "STATUS=Connected" 1219s === CONT TestServe/No_connection_because_the_port_file_has_port_0 1219s === CONT TestServe/Error_because_the_notifier_returns_an_error 1219s time="2024-07-19T07:45:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35051" 1219s time="2024-07-19T07:45:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39993" 1219s time="2024-07-19T07:45:44Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:44Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:45:45Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:45:55Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:45:55Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:45:55Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s === NAME TestServe/Success_with_systemd_notifier_returning_true 1219s daemon_test.go:182: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:182 1219s Error: Condition never satisfied 1219s Test: TestServe/Success_with_systemd_notifier_returning_true 1219s Messages: The daemon should have connected to the Windows Agent 1219s === CONT TestServe/Success 1219s time="2024-07-19T07:46:12Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:46:12Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:46:12Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:46:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33429" 1219s time="2024-07-19T07:46:12Z" level=debug msg="Building new daemon" 1219s === NAME TestServeAndQuit/Success_with_double_quit 1219s daemon_test.go:290: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:290 1219s Error: Condition never satisfied 1219s Test: TestServeAndQuit/Success_with_double_quit 1219s Messages: Systemd state should have been set to "STATUS=Connected" 1219s === CONT TestServeAndQuit/Success_with_forceful_quit 1219s time="2024-07-19T07:46:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35461" 1219s time="2024-07-19T07:46:15Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:46:40Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:46:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:46:44Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:46:44Z" 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" 1219s time="2024-07-19T07:46:44Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:46:45Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:46:45Z" 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" 1219s time="2024-07-19T07:46:45Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1219s time="2024-07-19T07:46:45Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:46:45Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:46:45Z" level=debug msg="All connections have now ended." 1219s === CONT TestServe/Error_because_the_context_is_pre-cancelled 1219s time="2024-07-19T07:46:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36777" 1219s time="2024-07-19T07:46:45Z" level=debug msg="Building new daemon" 1219s --- PASS: TestNew (0.00s) 1219s --- PASS: TestNew/Success (139.91s) 1219s --- PASS: TestNew/Error_when_WslPath_returns_error (100.58s) 1219s === CONT TestServe/No_connection_because_there_is_no_server 1219s time="2024-07-19T07:46:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36347" 1219s time="2024-07-19T07:46:51Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:46:55Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:04Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s === NAME TestServe/Error_because_WindowsHostAddress_returns_an_error 1219s daemon_test.go:196: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:196 1219s Error: Serve should have returned an error, but is still serving 1219s Test: TestServe/Error_because_WindowsHostAddress_returns_an_error 1219s === CONT TestReconnection/Success_connecting_after_failing_to_connect 1219s time="2024-07-19T07:47:10Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:47:21Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40257" 1219s time="2024-07-19T07:47:21Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file548383896/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 1219s time="2024-07-19T07:47:21Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:47:21Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:47:21Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:47:21Z" level=debug msg="All connections have now ended." 1219s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1219s time="2024-07-19T07:47:21Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:47:21Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:21Z" 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" 1219s time="2024-07-19T07:47:21Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:47:22Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:22Z" 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" 1219s time="2024-07-19T07:47:22Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1219s time="2024-07-19T07:47:22Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:47:22Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:47:22Z" level=debug msg="All connections have now ended." 1219s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 1219s time="2024-07-19T07:47:23Z" level=debug msg="Building new daemon" 1219s time="2024-07-19T07:47:25Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:47:25Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:25Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:47:25Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:25Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 1219s time="2024-07-19T07:47:25Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:47:25Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:47:25Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:47:41Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:42Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:47:42Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:47:42Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:47:42Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:33429" 1219s time="2024-07-19T07:47:42Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:47:43Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:47:43Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s === NAME TestServeAndQuit/Success_with_forceful_quit 1219s daemon_test.go:290: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:290 1219s Error: Condition never satisfied 1219s Test: TestServeAndQuit/Success_with_forceful_quit 1219s Messages: Systemd state should have been set to "STATUS=Connected" 1219s --- FAIL: TestServeAndQuit (0.00s) 1219s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (148.90s) 1219s --- FAIL: TestServeAndQuit/Success_with_graceful_quit (182.43s) 1219s --- FAIL: TestServeAndQuit/Success_with_double_quit (73.97s) 1219s --- FAIL: TestServeAndQuit/Success_with_forceful_quit (90.45s) 1219s time="2024-07-19T07:47:46Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:47:54Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:47:54Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:54Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:47:54Z" 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_connect411930241/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect411930241/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1219s time="2024-07-19T07:47:54Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:47:54Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1219s time="2024-07-19T07:47:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40349" 1219s time="2024-07-19T07:47:55Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:47:55Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:48:05Z" level=debug msg="Server: sent preface messages to all streams" 1219s time="2024-07-19T07:48:05Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1219s time="2024-07-19T07:48:05Z" level=info msg="MockWindowsAgent: Connected ready" 1219s time="2024-07-19T07:48:05Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1219s time="2024-07-19T07:48:06Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:36347" 1219s time="2024-07-19T07:48:06Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:48:06Z" 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:36347: connect: connection refused\"" 1219s time="2024-07-19T07:48:06Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:48:06Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:48:06Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:48:06Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:48:07Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:48:07Z" level=info msg="Waiting for active requests to close." 1219s time="2024-07-19T07:48:07Z" level=warning msg="Daemon: disconnected from Windows host" 1219s time="2024-07-19T07:48:07Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:48:07Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:48:07Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:07Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s --- FAIL: TestServe (0.00s) 1219s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (140.76s) 1219s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (141.25s) 1219s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (147.10s) 1219s --- PASS: TestServe/No_connection_because_there_are_no_certificates (181.70s) 1219s --- FAIL: TestServe/Success_with_systemd_notifier_returning_true (210.83s) 1219s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (98.54s) 1219s --- FAIL: TestServe/Error_because_WindowsHostAddress_returns_an_error (129.21s) 1219s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (141.25s) 1219s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (101.17s) 1219s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (103.52s) 1219s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (56.11s) 1219s --- PASS: TestServe/No_connection_because_there_is_no_server (77.11s) 1219s --- PASS: TestServe/Success (116.22s) 1219s time="2024-07-19T07:48:07Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s === NAME TestReconnection/Success_connecting_after_failing_to_connect 1219s daemon_test.go:392: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/daemon/daemon_test.go:392 1219s Error: Condition never satisfied 1219s Test: TestReconnection/Success_connecting_after_failing_to_connect 1219s Messages: Daemon never connected to agent's service 1219s time="2024-07-19T07:48:15Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:48:15Z" level=info msg="Stopping active requests." 1219s time="2024-07-19T07:48:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44615" 1219s time="2024-07-19T07:48:18Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:48:18Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:48:18Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:48:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38499" 1219s time="2024-07-19T07:48:19Z" level=debug msg="Ready state sent to systemd" 1219s time="2024-07-19T07:48:19Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:48:19Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:48:20Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:48:20Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:48:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38499" 1219s time="2024-07-19T07:48:36Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Updated systemd status to \"Connected\"" 1219s time="2024-07-19T07:48:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:44615" 1219s time="2024-07-19T07:48:36Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Updated systemd status to \"Connected\"" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:48:36Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s time="2024-07-19T07:48:45Z" level=debug msg="Server: sent preface messages to all streams" 1219s time="2024-07-19T07:48:45Z" level=debug msg="Server: sent preface messages to all streams" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: Connected ready" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: Connected ready" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1219s time="2024-07-19T07:48:45Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:45Z" level=warning msg="Daemon: disconnected from Windows host" 1219s time="2024-07-19T07:48:45Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:48:45Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1219s time="2024-07-19T07:48:45Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:45Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39037" 1219s time="2024-07-19T07:48:46Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:48:46Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:48:50Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39037" 1219s time="2024-07-19T07:48:50Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:48:50Z" level=debug msg="Updated systemd status to \"Connected\"" 1219s time="2024-07-19T07:48:50Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:48:50Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s time="2024-07-19T07:48:54Z" level=debug msg="Server: sent preface messages to all streams" 1219s time="2024-07-19T07:48:54Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1219s time="2024-07-19T07:48:54Z" level=info msg="MockWindowsAgent: Connected ready" 1219s time="2024-07-19T07:48:54Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1219s time="2024-07-19T07:48:54Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:54Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:48:54Z" level=info msg="Stopping active requests." 1219s time="2024-07-19T07:48:54Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:54Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:48:54Z" level=warning msg="Daemon: disconnected from Windows host" 1219s time="2024-07-19T07:48:54Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1219s time="2024-07-19T07:48:54Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1219s time="2024-07-19T07:48:54Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:48:54Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:49:50Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:49:50Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:49:50Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:49:50Z" level=warning msg="Daemon: disconnected from Windows host" 1219s time="2024-07-19T07:49:50Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:49:50Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:49:50Z" 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_dropped3611859513/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_previous_long-lived_connection_dropped3611859513/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1219s time="2024-07-19T07:49:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1219s time="2024-07-19T07:49:50Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1219s time="2024-07-19T07:49:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39133" 1219s time="2024-07-19T07:49:51Z" level=info msg="Daemon: connecting to Windows Agent" 1219s time="2024-07-19T07:49:51Z" level=debug msg="Updated systemd status to \"Connecting\"" 1219s time="2024-07-19T07:50:00Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39133" 1219s time="2024-07-19T07:50:00Z" level=info msg="Daemon: completed connection to Windows Agent" 1219s time="2024-07-19T07:50:00Z" level=debug msg="Updated systemd status to \"Connected\"" 1219s time="2024-07-19T07:50:00Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1219s time="2024-07-19T07:50:00Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1219s time="2024-07-19T07:50:07Z" level=debug msg="Server: sent preface messages to all streams" 1219s time="2024-07-19T07:50:07Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1219s time="2024-07-19T07:50:07Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1219s time="2024-07-19T07:50:07Z" level=info msg="MockWindowsAgent: Connected ready" 1219s time="2024-07-19T07:50:07Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:50:07Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:50:07Z" level=info msg="Stopping daemon requested." 1219s time="2024-07-19T07:50:07Z" level=warning msg="Daemon: disconnected from Windows host" 1219s time="2024-07-19T07:50:07Z" level=info msg="Reconnecting to Windows host in 2 seconds" 1219s time="2024-07-19T07:50:07Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1219s time="2024-07-19T07:50:07Z" level=debug msg="Updated systemd status to \"Stopped\"" 1219s time="2024-07-19T07:50:07Z" level=info msg="Stopping active requests." 1219s time="2024-07-19T07:50:07Z" level=debug msg="All connections have now ended." 1219s time="2024-07-19T07:50:07Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1219s --- FAIL: TestReconnection (0.00s) 1219s --- FAIL: TestReconnection/Success_connecting_after_failing_to_connect (69.92s) 1219s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (91.64s) 1219s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (165.67s) 1219s FAIL 1219s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 461.749s 1219s === RUN TestConnect 1219s === PAUSE TestConnect 1219s === RUN TestSendAndRecv 1219s --- PASS: TestSendAndRecv (0.68s) 1219s === RUN TestServe 1219s === PAUSE TestServe 1219s === RUN TestStop 1219s === PAUSE TestStop 1219s === RUN TestWithProMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithProMock (0.00s) 1219s === RUN TestWithWslPathMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslPathMock (0.00s) 1219s === RUN TestWithWslInfoMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslInfoMock (0.00s) 1219s === RUN TestWithCmdExeMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithCmdExeMock (0.00s) 1219s === CONT TestConnect 1219s === RUN TestConnect/Success 1219s === PAUSE TestConnect/Success 1219s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 1219s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 1219s === CONT TestConnect/Success 1219s === CONT TestStop 1219s === CONT TestServe 1219s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 1219s time="2024-07-19T07:42:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39941" 1219s time="2024-07-19T07:42:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33593" 1219s --- PASS: TestConnect (0.00s) 1219s --- PASS: TestConnect/Success (0.36s) 1219s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.00s) 1219s === NAME TestStop 1219s server_test.go:116: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/streams/server_test.go:116 1219s Error: Condition never satisfied 1219s Test: TestStop 1219s Messages: Setup: Agent service never became ready 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s === NAME TestServe 1219s server_test.go:41: 1219s Error Trace: /tmp/autopkgtest.omIHT6/build.3ed/src/internal/streams/server_test.go:41 1219s Error: Condition never satisfied 1219s Test: TestServe 1219s Messages: Setup: Agent service never became ready 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s time="2024-07-19T07:42:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1219s --- FAIL: TestServe (21.81s) 1219s --- FAIL: TestStop (22.69s) 1219s FAIL 1219s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 33.284s 1219s === RUN TestInfo 1219s === PAUSE TestInfo 1219s === RUN TestWslDistroName 1219s === PAUSE TestWslDistroName 1219s === RUN TestUserProfileDir 1219s === PAUSE TestUserProfileDir 1219s === RUN TestProStatus 1219s === PAUSE TestProStatus 1219s === RUN TestProAttach 1219s === PAUSE TestProAttach 1219s === RUN TestProDetach 1219s === PAUSE TestProDetach 1219s === RUN TestLandscapeEnable 1219s === PAUSE TestLandscapeEnable 1219s === RUN TestWindowsHostAddress 1219s === PAUSE TestWindowsHostAddress 1219s === RUN TestLandscapeDisable 1219s === PAUSE TestLandscapeDisable 1219s === RUN TestRealBackend 1219s === PAUSE TestRealBackend 1219s === RUN TestWithProMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithProMock (0.14s) 1219s === RUN TestWithLandscapeConfigMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1219s === RUN TestWithWslPathMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslPathMock (0.00s) 1219s === RUN TestWithWslInfoMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithWslInfoMock (0.00s) 1219s === RUN TestWithCmdExeMock 1219s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 1219s --- SKIP: TestWithCmdExeMock (0.00s) 1219s === CONT TestInfo 1219s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 1219s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 1219s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1219s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1219s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 1219s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 1219s === RUN TestInfo/Success 1219s === PAUSE TestInfo/Success 1219s === RUN TestInfo/Error_when_WslDistroName_fails 1219s === PAUSE TestInfo/Error_when_WslDistroName_fails 1219s === RUN TestInfo/Error_when_pro_status_command_fails 1219s === PAUSE TestInfo/Error_when_pro_status_command_fails 1219s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 1219s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 1219s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 1219s === CONT TestUserProfileDir 1219s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1219s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1219s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1219s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1219s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1219s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1219s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1219s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1219s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 1219s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 1219s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1219s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1219s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1219s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1219s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1219s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1219s === RUN TestUserProfileDir/Error_on_cmd.exe_error 1219s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 1219s === RUN TestUserProfileDir/Error_on_wslpath_error 1219s === PAUSE TestUserProfileDir/Error_on_wslpath_error 1219s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1219s === CONT TestRealBackend 1219s === CONT TestUserProfileDir/Error_on_cmd.exe_error 1219s === CONT TestLandscapeDisable 1219s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1219s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1219s === RUN TestLandscapeDisable/Success 1219s === PAUSE TestLandscapeDisable/Success 1219s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1219s === CONT TestWindowsHostAddress 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1219s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1219s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1219s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1219s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1219s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1219s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1219s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1219s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1219s === RUN TestWindowsHostAddress/Success_without_NAT 1219s === PAUSE TestWindowsHostAddress/Success_without_NAT 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 1219s === CONT TestLandscapeEnable 1219s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1219s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1219s === RUN TestLandscapeEnable/Success 1219s === PAUSE TestLandscapeEnable/Success 1219s === RUN TestLandscapeEnable/Success_overriding_computer_title 1219s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 1219s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1219s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1219s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1219s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1219s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1219s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1219s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1219s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1219s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1219s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1219s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1219s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1219s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1219s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1219s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1219s === CONT TestProDetach 1219s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1219s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1219s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 1219s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 1219s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1219s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1219s === RUN TestProDetach/success_on_unattached_distro 1219s === PAUSE TestProDetach/success_on_unattached_distro 1219s === RUN TestProDetach/success_on_attached_distro 1219s === PAUSE TestProDetach/success_on_attached_distro 1219s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1219s --- PASS: TestRealBackend (0.15s) 1219s === CONT TestProAttach 1219s === RUN TestProAttach/success 1219s === PAUSE TestProAttach/success 1219s === RUN TestProAttach/error_on_'pro_attach'_error 1219s === PAUSE TestProAttach/error_on_'pro_attach'_error 1219s === CONT TestProStatus 1219s === RUN TestProStatus/success_on_unattached_distro 1219s === PAUSE TestProStatus/success_on_unattached_distro 1219s === RUN TestProStatus/success_on_attached_distro 1219s === PAUSE TestProStatus/success_on_attached_distro 1219s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 1219s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 1219s === RUN TestProStatus/error_on_'pro_attach'_error 1219s === PAUSE TestProStatus/error_on_'pro_attach'_error 1219s === CONT TestUserProfileDir/Error_on_wslpath_error 1219s === CONT TestLandscapeDisable/Success 1219s === CONT TestWindowsHostAddress/Success_without_NAT 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 1219s --- PASS: TestLandscapeDisable (0.00s) 1219s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (114.22s) 1219s --- PASS: TestLandscapeDisable/Success (114.37s) 1219s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1219s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 1219s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 1219s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1219s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1219s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 1219s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1219s time="2024-07-19T07:45:14Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1219s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1219s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 1219s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1219s time="2024-07-19T07:45:21Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 1219s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1219s === CONT TestLandscapeEnable/Success_overriding_computer_title 1219s === CONT TestLandscapeEnable/Success 1219s --- PASS: TestWindowsHostAddress (0.00s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (115.30s) 1219s --- PASS: TestWindowsHostAddress/Success_without_NAT (23.29s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (27.49s) 1219s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (24.60s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (34.71s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (33.74s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (42.19s) 1219s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (31.35s) 1219s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (28.37s) 1219s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (33.67s) 1219s === CONT TestProDetach/success_on_attached_distro 1219s time="2024-07-19T07:45:39Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 1219s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1219s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 1219s === CONT TestProDetach/success_on_unattached_distro 1219s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1219s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 1219s === NAME TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 1219s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 1219s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 1219s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1219s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1219s === NAME TestLandscapeEnable/Success_overriding_computer_title 1219s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 1219s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1219s === NAME TestLandscapeEnable/Success 1219s system_test.go:456: testdata/TestLandscapeEnable/golden/success 1219s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1219s system_test.go:205: Removing default proc/mounts 1219s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1219s === CONT TestInfo/Error_when_WslDistroName_fails 1219s === CONT TestWslDistroName 1219s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1219s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1219s === RUN TestWslDistroName/Success_using_wslpath 1219s === PAUSE TestWslDistroName/Success_using_wslpath 1219s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1219s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1219s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1219s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1219s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 1219s === CONT TestInfo/Error_when_pro_status_command_fails 1219s --- PASS: TestProDetach (0.00s) 1219s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (112.34s) 1219s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (59.60s) 1219s --- PASS: TestProDetach/success_on_attached_distro (74.14s) 1219s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (65.01s) 1219s --- PASS: TestProDetach/success_on_unattached_distro (79.54s) 1219s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1219s system_test.go:205: Removing default proc/mounts 1219s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 1219s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 1219s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 1219s --- PASS: TestLandscapeEnable (0.00s) 1219s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (3.10s) 1219s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 1219s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 1219s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (25.86s) 1219s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (31.93s) 1219s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (32.38s) 1219s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (43.28s) 1219s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (66.77s) 1219s --- PASS: TestLandscapeEnable/Success (71.47s) 1219s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (114.42s) 1219s === CONT TestInfo/Success 1219s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1219s === CONT TestProAttach/success 1219s === CONT TestProStatus/success_on_unattached_distro 1219s === CONT TestProAttach/error_on_'pro_attach'_error 1219s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 1219s === CONT TestProStatus/error_on_'pro_attach'_error 1219s --- PASS: TestUserProfileDir (0.00s) 1219s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (114.20s) 1219s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (133.42s) 1219s --- PASS: TestUserProfileDir/Error_on_wslpath_error (138.52s) 1219s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 1219s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 1219s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 1219s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (82.50s) 1219s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (54.18s) 1219s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (53.69s) 1219s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (70.14s) 1219s === CONT TestProStatus/success_on_attached_distro 1219s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1219s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1219s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1219s --- PASS: TestInfo (0.07s) 1219s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (116.75s) 1219s --- PASS: TestInfo/Error_when_pro_status_command_fails (25.60s) 1219s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (28.58s) 1219s --- PASS: TestInfo/Error_when_WslDistroName_fails (33.88s) 1219s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (35.11s) 1219s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (33.11s) 1219s --- PASS: TestInfo/Success (34.60s) 1219s === CONT TestWslDistroName/Success_using_wslpath 1219s --- PASS: TestProAttach (0.00s) 1219s --- PASS: TestProAttach/success (28.82s) 1219s --- PASS: TestProAttach/error_on_'pro_attach'_error (24.81s) 1219s --- PASS: TestProStatus (0.00s) 1219s --- PASS: TestProStatus/success_on_unattached_distro (30.79s) 1219s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (20.66s) 1219s --- PASS: TestProStatus/error_on_'pro_attach'_error (17.87s) 1219s --- PASS: TestProStatus/success_on_attached_distro (22.55s) 1219s --- PASS: TestWslDistroName (0.00s) 1219s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.22s) 1219s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (20.51s) 1219s --- PASS: TestWslDistroName/Success_using_wslpath (20.20s) 1219s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (20.55s) 1219s PASS 1219s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 344.307s 1219s FAIL 1221s autopkgtest [07:50:09]: test command1: -----------------------] 1226s autopkgtest [07:50:14]: test command1: - - - - - - - - - - results - - - - - - - - - - 1226s command1 FAIL non-zero exit status 1 1233s autopkgtest [07:50:21]: @@@@@@@@@@@@@@@@@@@@ summary 1233s command1 FAIL non-zero exit status 1