0s autopkgtest [18:37:44]: starting date and time: 2024-04-05 18:37:44+0000 0s autopkgtest [18:37:44]: git checkout: 31124158 autopkgtest: take Paride's WIP change regarding wrong src pkg selection 0s autopkgtest [18:37:44]: host juju-7f2275-prod-proposed-migration-environment-4; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.r1n7d8aj/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,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/31.2.3 -- lxd -r lxd-armhf-10.145.243.5 lxd-armhf-10.145.243.5:autopkgtest/ubuntu/noble/armhf 30s autopkgtest [18:38:14]: testbed dpkg architecture: armhf 32s autopkgtest [18:38:16]: testbed apt version: 2.7.14build2 32s autopkgtest [18:38:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 39s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 40s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [16.9 kB] 40s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [938 kB] 40s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [111 kB] 40s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [4272 B] 40s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [178 kB] 40s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 40s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [760 B] 40s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 40s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [908 kB] 40s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 40s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf Packages [20.6 kB] 40s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 42s Fetched 2305 kB in 1s (2266 kB/s) 42s Reading package lists... 58s tee: /proc/self/fd/2: Permission denied 80s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 80s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 80s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 80s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 81s Reading package lists... 81s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Calculating upgrade... 83s The following packages will be upgraded: 83s bsdextrautils bsdutils eject fdisk fwupd gcc-13-base gir1.2-girepository-2.0 83s libarchive13t64 libaudit-common libaudit1 libblkid1 libc-bin libc6 libfdisk1 83s libfwupd2 libgirepository-1.0-1 libgpm2 libgudev-1.0-0 libmount1 83s libnss-systemd libpam-systemd libpolkit-agent-1-0 libpolkit-gobject-1-0 83s libproc2-0 libsmartcols1 libsystemd-shared libsystemd0 libudev1 libuuid1 83s locales man-db mount procps python3-gi shared-mime-info systemd systemd-dev 83s systemd-resolved systemd-sysv systemd-timesyncd ubuntu-pro-client 83s ubuntu-pro-client-l10n udev util-linux uuid-runtime 83s 45 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Need to get 26.8 MB of archives. 83s After this operation, 45.1 kB of additional disk space will be used. 83s Get:1 http://ftpmaster.internal/ubuntu noble/main armhf bsdutils armhf 1:2.39.3-9ubuntu4 [101 kB] 83s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf util-linux armhf 2.39.3-9ubuntu4 [1216 kB] 84s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf libc6 armhf 2.39-0ubuntu8 [2828 kB] 84s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf libc-bin armhf 2.39-0ubuntu8 [530 kB] 84s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf mount armhf 2.39.3-9ubuntu4 [134 kB] 84s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf libnss-systemd armhf 255.4-1ubuntu7 [148 kB] 84s Get:7 http://ftpmaster.internal/ubuntu noble/main armhf systemd-dev all 255.4-1ubuntu7 [104 kB] 84s Get:8 http://ftpmaster.internal/ubuntu noble/main armhf libblkid1 armhf 2.39.3-9ubuntu4 [160 kB] 84s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf systemd-timesyncd armhf 255.4-1ubuntu7 [36.0 kB] 84s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf systemd-resolved armhf 255.4-1ubuntu7 [289 kB] 84s Get:11 http://ftpmaster.internal/ubuntu noble/main armhf libsystemd-shared armhf 255.4-1ubuntu7 [2010 kB] 84s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf libsystemd0 armhf 255.4-1ubuntu7 [411 kB] 84s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf systemd-sysv armhf 255.4-1ubuntu7 [11.9 kB] 84s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf libpam-systemd armhf 255.4-1ubuntu7 [216 kB] 84s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf systemd armhf 255.4-1ubuntu7 [3502 kB] 84s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf udev armhf 255.4-1ubuntu7 [1852 kB] 84s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf libudev1 armhf 255.4-1ubuntu7 [166 kB] 84s Get:18 http://ftpmaster.internal/ubuntu noble-proposed/main armhf libaudit-common all 1:3.1.2-2.1build1 [5736 B] 84s Get:19 http://ftpmaster.internal/ubuntu noble-proposed/main armhf libaudit1 armhf 1:3.1.2-2.1build1 [44.4 kB] 84s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libmount1 armhf 2.39.3-9ubuntu4 [171 kB] 84s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libuuid1 armhf 2.39.3-9ubuntu4 [34.6 kB] 84s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libfdisk1 armhf 2.39.3-9ubuntu4 [196 kB] 84s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libsmartcols1 armhf 2.39.3-9ubuntu4 [117 kB] 84s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf uuid-runtime armhf 2.39.3-9ubuntu4 [41.7 kB] 84s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf libproc2-0 armhf 2:4.0.4-4ubuntu3 [49.0 kB] 84s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf procps armhf 2:4.0.4-4ubuntu3 [700 kB] 84s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf eject armhf 2.39.3-9ubuntu4 [43.2 kB] 84s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf libgirepository-1.0-1 armhf 1.80.1-1 [107 kB] 84s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf locales all 2.39-0ubuntu8 [4234 kB] 84s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf gir1.2-girepository-2.0 armhf 1.80.1-1 [24.5 kB] 84s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf python3-gi armhf 3.48.1-1build1 [219 kB] 84s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf shared-mime-info armhf 2.4-4 [471 kB] 84s Get:33 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 31.2.3 [19.4 kB] 84s Get:34 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 31.2.3 [216 kB] 84s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf bsdextrautils armhf 2.39.3-9ubuntu4 [78.7 kB] 84s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf libgpm2 armhf 1.20.7-11 [13.6 kB] 84s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf man-db armhf 2.12.0-4build1 [1202 kB] 84s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf fdisk armhf 2.39.3-9ubuntu4 [135 kB] 84s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libarchive13t64 armhf 3.7.2-2 [330 kB] 84s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf libfwupd2 armhf 1.9.15-2build1 [123 kB] 84s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf libgudev-1.0-0 armhf 1:238-5ubuntu1 [13.8 kB] 84s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf libpolkit-agent-1-0 armhf 124-2ubuntu1 [15.3 kB] 84s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf libpolkit-gobject-1-0 armhf 124-2ubuntu1 [44.3 kB] 84s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf fwupd armhf 1.9.15-2build1 [4350 kB] 84s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-base armhf 13.2.0-23ubuntu3 [48.6 kB] 85s Preconfiguring packages ... 85s Fetched 26.8 MB in 1s (18.8 MB/s) 85s (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 ... 58475 files and directories currently installed.) 85s Preparing to unpack .../bsdutils_1%3a2.39.3-9ubuntu4_armhf.deb ... 85s Unpacking bsdutils (1:2.39.3-9ubuntu4) over (1:2.39.3-9ubuntu2) ... 85s Setting up bsdutils (1:2.39.3-9ubuntu4) ... 85s (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 ... 58475 files and directories currently installed.) 85s Preparing to unpack .../util-linux_2.39.3-9ubuntu4_armhf.deb ... 85s Unpacking util-linux (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 86s Setting up util-linux (2.39.3-9ubuntu4) ... 86s fstrim.service is a disabled or a static unit not running, not starting it. 86s (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 ... 58475 files and directories currently installed.) 86s Preparing to unpack .../libc6_2.39-0ubuntu8_armhf.deb ... 86s Unpacking libc6:armhf (2.39-0ubuntu8) over (2.39-0ubuntu6) ... 87s Setting up libc6:armhf (2.39-0ubuntu8) ... 87s (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 ... 58475 files and directories currently installed.) 87s Preparing to unpack .../libc-bin_2.39-0ubuntu8_armhf.deb ... 87s Unpacking libc-bin (2.39-0ubuntu8) over (2.39-0ubuntu6) ... 87s Setting up libc-bin (2.39-0ubuntu8) ... 88s (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 ... 58475 files and directories currently installed.) 88s Preparing to unpack .../mount_2.39.3-9ubuntu4_armhf.deb ... 88s Unpacking mount (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 88s Preparing to unpack .../libnss-systemd_255.4-1ubuntu7_armhf.deb ... 88s Unpacking libnss-systemd:armhf (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../systemd-dev_255.4-1ubuntu7_all.deb ... 88s Unpacking systemd-dev (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../libblkid1_2.39.3-9ubuntu4_armhf.deb ... 88s Unpacking libblkid1:armhf (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 88s Setting up libblkid1:armhf (2.39.3-9ubuntu4) ... 88s (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 ... 58475 files and directories currently installed.) 88s Preparing to unpack .../systemd-timesyncd_255.4-1ubuntu7_armhf.deb ... 88s Unpacking systemd-timesyncd (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../systemd-resolved_255.4-1ubuntu7_armhf.deb ... 88s Unpacking systemd-resolved (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../libsystemd-shared_255.4-1ubuntu7_armhf.deb ... 88s Unpacking libsystemd-shared:armhf (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../libsystemd0_255.4-1ubuntu7_armhf.deb ... 88s Unpacking libsystemd0:armhf (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Setting up libsystemd0:armhf (255.4-1ubuntu7) ... 88s (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 ... 58475 files and directories currently installed.) 88s Preparing to unpack .../systemd-sysv_255.4-1ubuntu7_armhf.deb ... 88s Unpacking systemd-sysv (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../libpam-systemd_255.4-1ubuntu7_armhf.deb ... 88s Unpacking libpam-systemd:armhf (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 88s Preparing to unpack .../systemd_255.4-1ubuntu7_armhf.deb ... 88s Unpacking systemd (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 89s Preparing to unpack .../udev_255.4-1ubuntu7_armhf.deb ... 89s Unpacking udev (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 89s Preparing to unpack .../libudev1_255.4-1ubuntu7_armhf.deb ... 89s Unpacking libudev1:armhf (255.4-1ubuntu7) over (255.4-1ubuntu6) ... 89s Setting up libudev1:armhf (255.4-1ubuntu7) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../libaudit-common_1%3a3.1.2-2.1build1_all.deb ... 89s Unpacking libaudit-common (1:3.1.2-2.1build1) over (1:3.1.2-2.1) ... 89s Setting up libaudit-common (1:3.1.2-2.1build1) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../libaudit1_1%3a3.1.2-2.1build1_armhf.deb ... 89s Unpacking libaudit1:armhf (1:3.1.2-2.1build1) over (1:3.1.2-2.1) ... 89s Setting up libaudit1:armhf (1:3.1.2-2.1build1) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../libmount1_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking libmount1:armhf (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Setting up libmount1:armhf (2.39.3-9ubuntu4) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../libuuid1_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking libuuid1:armhf (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Setting up libuuid1:armhf (2.39.3-9ubuntu4) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../libfdisk1_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking libfdisk1:armhf (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Preparing to unpack .../libsmartcols1_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking libsmartcols1:armhf (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Setting up libsmartcols1:armhf (2.39.3-9ubuntu4) ... 89s (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 ... 58475 files and directories currently installed.) 89s Preparing to unpack .../00-uuid-runtime_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking uuid-runtime (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Preparing to unpack .../01-libproc2-0_2%3a4.0.4-4ubuntu3_armhf.deb ... 89s Unpacking libproc2-0:armhf (2:4.0.4-4ubuntu3) over (2:4.0.4-4ubuntu1) ... 89s Preparing to unpack .../02-procps_2%3a4.0.4-4ubuntu3_armhf.deb ... 89s Unpacking procps (2:4.0.4-4ubuntu3) over (2:4.0.4-4ubuntu1) ... 89s Preparing to unpack .../03-eject_2.39.3-9ubuntu4_armhf.deb ... 89s Unpacking eject (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 89s Preparing to unpack .../04-libgirepository-1.0-1_1.80.1-1_armhf.deb ... 89s Unpacking libgirepository-1.0-1:armhf (1.80.1-1) over (1.79.1-1ubuntu6) ... 89s Preparing to unpack .../05-locales_2.39-0ubuntu8_all.deb ... 89s Unpacking locales (2.39-0ubuntu8) over (2.39-0ubuntu6) ... 90s Preparing to unpack .../06-gir1.2-girepository-2.0_1.80.1-1_armhf.deb ... 90s Unpacking gir1.2-girepository-2.0:armhf (1.80.1-1) over (1.79.1-1ubuntu6) ... 90s Preparing to unpack .../07-python3-gi_3.48.1-1build1_armhf.deb ... 90s Unpacking python3-gi (3.48.1-1build1) over (3.47.0-3build1) ... 90s Preparing to unpack .../08-shared-mime-info_2.4-4_armhf.deb ... 90s Unpacking shared-mime-info (2.4-4) over (2.4-1build1) ... 90s Preparing to unpack .../09-ubuntu-pro-client-l10n_31.2.3_armhf.deb ... 90s Unpacking ubuntu-pro-client-l10n (31.2.3) over (31.2.2build1) ... 90s Preparing to unpack .../10-ubuntu-pro-client_31.2.3_armhf.deb ... 90s Unpacking ubuntu-pro-client (31.2.3) over (31.2.2build1) ... 90s Preparing to unpack .../11-bsdextrautils_2.39.3-9ubuntu4_armhf.deb ... 90s Unpacking bsdextrautils (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 90s Preparing to unpack .../12-libgpm2_1.20.7-11_armhf.deb ... 90s Unpacking libgpm2:armhf (1.20.7-11) over (1.20.7-10build1) ... 90s Preparing to unpack .../13-man-db_2.12.0-4build1_armhf.deb ... 90s Unpacking man-db (2.12.0-4build1) over (2.12.0-3build4) ... 90s Preparing to unpack .../14-fdisk_2.39.3-9ubuntu4_armhf.deb ... 90s Unpacking fdisk (2.39.3-9ubuntu4) over (2.39.3-9ubuntu2) ... 90s Preparing to unpack .../15-libarchive13t64_3.7.2-2_armhf.deb ... 90s Unpacking libarchive13t64:armhf (3.7.2-2) over (3.7.2-1.1ubuntu2) ... 90s Preparing to unpack .../16-libfwupd2_1.9.15-2build1_armhf.deb ... 90s Unpacking libfwupd2:armhf (1.9.15-2build1) over (1.9.15-2) ... 90s Preparing to unpack .../17-libgudev-1.0-0_1%3a238-5ubuntu1_armhf.deb ... 90s Unpacking libgudev-1.0-0:armhf (1:238-5ubuntu1) over (1:238-3ubuntu2) ... 90s Preparing to unpack .../18-libpolkit-agent-1-0_124-2ubuntu1_armhf.deb ... 90s Unpacking libpolkit-agent-1-0:armhf (124-2ubuntu1) over (124-1ubuntu1) ... 90s Preparing to unpack .../19-libpolkit-gobject-1-0_124-2ubuntu1_armhf.deb ... 90s Unpacking libpolkit-gobject-1-0:armhf (124-2ubuntu1) over (124-1ubuntu1) ... 90s Preparing to unpack .../20-fwupd_1.9.15-2build1_armhf.deb ... 91s Unpacking fwupd (1.9.15-2build1) over (1.9.15-2) ... 91s Preparing to unpack .../21-gcc-13-base_13.2.0-23ubuntu3_armhf.deb ... 91s Unpacking gcc-13-base:armhf (13.2.0-23ubuntu3) over (13.2.0-21ubuntu1) ... 91s Setting up libgpm2:armhf (1.20.7-11) ... 91s Setting up bsdextrautils (2.39.3-9ubuntu4) ... 91s Setting up libfwupd2:armhf (1.9.15-2build1) ... 91s Setting up systemd-dev (255.4-1ubuntu7) ... 91s Setting up locales (2.39-0ubuntu8) ... 91s Generating locales (this might take a while)... 94s en_US.UTF-8... done 94s Generation complete. 94s Setting up man-db (2.12.0-4build1) ... 94s Updating database of manual pages ... 95s apparmor_parser: Unable to replace "/usr/bin/man". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 95s 96s man-db.service is a disabled or a static unit not running, not starting it. 96s Setting up eject (2.39.3-9ubuntu4) ... 96s Setting up gcc-13-base:armhf (13.2.0-23ubuntu3) ... 96s Setting up shared-mime-info (2.4-4) ... 96s Setting up libproc2-0:armhf (2:4.0.4-4ubuntu3) ... 96s Setting up libsystemd-shared:armhf (255.4-1ubuntu7) ... 96s Setting up libfdisk1:armhf (2.39.3-9ubuntu4) ... 96s Setting up mount (2.39.3-9ubuntu4) ... 96s Setting up uuid-runtime (2.39.3-9ubuntu4) ... 97s uuidd.service is a disabled or a static unit not running, not starting it. 97s Setting up procps (2:4.0.4-4ubuntu3) ... 98s Setting up libgirepository-1.0-1:armhf (1.80.1-1) ... 98s Setting up libarchive13t64:armhf (3.7.2-2) ... 98s Setting up ubuntu-pro-client (31.2.3) ... 98s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 98s 99s Setting up libgudev-1.0-0:armhf (1:238-5ubuntu1) ... 99s Setting up libpolkit-gobject-1-0:armhf (124-2ubuntu1) ... 99s Setting up ubuntu-pro-client-l10n (31.2.3) ... 99s Setting up systemd (255.4-1ubuntu7) ... 100s Setting up fdisk (2.39.3-9ubuntu4) ... 100s Setting up gir1.2-girepository-2.0:armhf (1.80.1-1) ... 100s Setting up python3-gi (3.48.1-1build1) ... 100s Setting up systemd-timesyncd (255.4-1ubuntu7) ... 100s Setting up udev (255.4-1ubuntu7) ... 101s Setting up libpolkit-agent-1-0:armhf (124-2ubuntu1) ... 101s Setting up fwupd (1.9.15-2build1) ... 101s fwupd-offline-update.service is a disabled or a static unit not running, not starting it. 101s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 102s fwupd.service is a disabled or a static unit not running, not starting it. 102s Setting up systemd-resolved (255.4-1ubuntu7) ... 102s Setting up systemd-sysv (255.4-1ubuntu7) ... 102s Setting up libnss-systemd:armhf (255.4-1ubuntu7) ... 102s Setting up libpam-systemd:armhf (255.4-1ubuntu7) ... 102s Processing triggers for libc-bin (2.39-0ubuntu8) ... 102s Processing triggers for dbus (1.14.10-4ubuntu3) ... 102s Processing triggers for initramfs-tools (0.142ubuntu24) ... 103s Reading package lists... 103s Building dependency tree... 103s Reading state information... 104s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s autopkgtest [18:39:30]: rebooting testbed after setup commands that affected boot 143s autopkgtest [18:40:07]: testbed running kernel: Linux 5.15.0-101-generic #111-Ubuntu SMP Wed Mar 6 18:01:01 UTC 2024 167s autopkgtest [18:40:31]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 177s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2build1 (dsc) [2084 B] 177s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2build1 (tar) [1745 kB] 177s gpgv: Signature made Mon Apr 1 08:33:08 2024 UTC 177s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 177s gpgv: Can't check signature: No public key 177s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.2build1.dsc: no acceptable signature found 177s autopkgtest [18:40:41]: testing package wsl-pro-service version 0.1.2build1 179s autopkgtest [18:40:43]: build not needed 181s autopkgtest [18:40:45]: test command1: preparing testbed 191s Reading package lists... 191s Building dependency tree... 191s Reading state information... 192s Starting pkgProblemResolver with broken count: 0 192s Starting 2 pkgProblemResolver with broken count: 0 192s Done 193s The following additional packages will be installed: 193s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 193s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 193s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 193s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 193s gcc-13-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext golang-1.22-go 193s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 193s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 193s libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl libgcc-13-dev 193s libgomp1 libisl23 libmpc3 libstdc++-13-dev libsub-override-perl libtool 193s libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 193s Suggested packages: 193s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 193s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 193s gcc-doc gdb-arm-linux-gnueabihf gettext-doc libasprintf-dev libgettextpo-dev 193s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 193s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 193s Recommended packages: 193s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 193s libltdl-dev libmail-sendmail-perl 193s The following NEW packages will be installed: 193s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 193s cpp cpp-13 cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper 193s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 193s g++-13 g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 193s gcc-13-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext golang-1.22-go 193s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 193s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 193s libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl libgcc-13-dev 193s libgomp1 libisl23 libmpc3 libstdc++-13-dev libsub-override-perl libtool 193s libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 193s 0 upgraded, 53 newly installed, 0 to remove and 0 not upgraded. 193s Need to get 95.3 MB/95.3 MB of archives. 193s After this operation, 377 MB of additional disk space will be used. 193s Get:1 /tmp/autopkgtest.jFtph0/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [760 B] 193s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf m4 armhf 1.4.19-4 [235 kB] 193s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf autoconf all 2.71-3 [339 kB] 193s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf autotools-dev all 20220109.1 [44.9 kB] 193s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf automake all 1:1.16.5-1.3ubuntu1 [558 kB] 193s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf autopoint all 0.21-14ubuntu1 [422 kB] 193s Get:7 http://ftpmaster.internal/ubuntu noble/main armhf libc-dev-bin armhf 2.39-0ubuntu8 [19.1 kB] 193s Get:8 http://ftpmaster.internal/ubuntu noble/main armhf linux-libc-dev armhf 6.8.0-20.20 [1555 kB] 194s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf libcrypt-dev armhf 1:4.4.36-4 [136 kB] 194s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf rpcsvc-proto armhf 1.4.2-0ubuntu6 [63.7 kB] 194s Get:11 http://ftpmaster.internal/ubuntu noble/main armhf libc6-dev armhf 2.39-0ubuntu8 [1352 kB] 194s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf libisl23 armhf 0.26-3 [595 kB] 194s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libmpc3 armhf 1.3.1-1 [46.4 kB] 194s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu3 [8759 kB] 194s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-23ubuntu3 [1034 B] 194s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 194s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 194s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240330-1ubuntu2 [39.1 kB] 194s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240330-1ubuntu2 [125 kB] 194s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240330-1ubuntu2 [7846 B] 194s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240330-1ubuntu2 [2940 kB] 194s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240330-1ubuntu2 [1153 kB] 194s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-23ubuntu3 [899 kB] 194s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu3 [16.8 MB] 194s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13 armhf 13.2.0-23ubuntu3 [450 kB] 194s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf gcc-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [1220 B] 194s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf gcc armhf 4:13.2.0-7ubuntu1 [5022 B] 194s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf libstdc++-13-dev armhf 13.2.0-23ubuntu3 [2455 kB] 194s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf g++-13-arm-linux-gnueabihf armhf 13.2.0-23ubuntu3 [9939 kB] 195s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf g++-13 armhf 13.2.0-23ubuntu3 [14.5 kB] 195s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf g++-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [966 B] 195s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf g++ armhf 4:13.2.0-7ubuntu1 [1090 B] 195s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf build-essential armhf 12.10ubuntu1 [4928 B] 195s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 195s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf libtool all 2.4.7-7 [166 kB] 195s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf dh-autoreconf all 20 [16.1 kB] 195s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 195s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf libsub-override-perl all 0.10-1 [10.0 kB] 195s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 195s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf dh-strip-nondeterminism all 1.13.1-1 [5362 B] 195s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf libdw1t64 armhf 0.190-1.1build3 [235 kB] 195s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5build2 [42.3 kB] 195s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1build6 [116 kB] 195s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu1 [800 kB] 195s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 195s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 195s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.14.1ubuntu5 [869 kB] 195s Get:48 http://ftpmaster.internal/ubuntu noble/universe armhf dh-apport all 2.28.0-0ubuntu1 [16.9 kB] 195s Get:49 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.22-src all 1.22.1-1build1 [19.7 MB] 195s Get:50 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.22-go armhf 1.22.1-1build1 [23.7 MB] 196s Get:51 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.22~2build1 [5078 B] 196s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.22~2build1 [43.9 kB] 196s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 197s Fetched 95.3 MB in 3s (29.6 MB/s) 197s Selecting previously unselected package m4. 197s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 58476 files and directories currently installed.) 197s Preparing to unpack .../00-m4_1.4.19-4_armhf.deb ... 197s Unpacking m4 (1.4.19-4) ... 197s Selecting previously unselected package autoconf. 197s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 197s Unpacking autoconf (2.71-3) ... 197s Selecting previously unselected package autotools-dev. 197s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 197s Unpacking autotools-dev (20220109.1) ... 197s Selecting previously unselected package automake. 197s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 197s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 197s Selecting previously unselected package autopoint. 197s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 197s Unpacking autopoint (0.21-14ubuntu1) ... 197s Selecting previously unselected package libc-dev-bin. 197s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu8_armhf.deb ... 197s Unpacking libc-dev-bin (2.39-0ubuntu8) ... 197s Selecting previously unselected package linux-libc-dev:armhf. 197s Preparing to unpack .../06-linux-libc-dev_6.8.0-20.20_armhf.deb ... 197s Unpacking linux-libc-dev:armhf (6.8.0-20.20) ... 197s Selecting previously unselected package libcrypt-dev:armhf. 197s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4_armhf.deb ... 197s Unpacking libcrypt-dev:armhf (1:4.4.36-4) ... 197s Selecting previously unselected package rpcsvc-proto. 197s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu6_armhf.deb ... 197s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 197s Selecting previously unselected package libc6-dev:armhf. 197s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu8_armhf.deb ... 197s Unpacking libc6-dev:armhf (2.39-0ubuntu8) ... 197s Selecting previously unselected package libisl23:armhf. 197s Preparing to unpack .../10-libisl23_0.26-3_armhf.deb ... 197s Unpacking libisl23:armhf (0.26-3) ... 197s Selecting previously unselected package libmpc3:armhf. 197s Preparing to unpack .../11-libmpc3_1.3.1-1_armhf.deb ... 197s Unpacking libmpc3:armhf (1.3.1-1) ... 197s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 197s Preparing to unpack .../12-cpp-13-arm-linux-gnueabihf_13.2.0-23ubuntu3_armhf.deb ... 197s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 198s Selecting previously unselected package cpp-13. 198s Preparing to unpack .../13-cpp-13_13.2.0-23ubuntu3_armhf.deb ... 198s Unpacking cpp-13 (13.2.0-23ubuntu3) ... 198s Selecting previously unselected package cpp-arm-linux-gnueabihf. 198s Preparing to unpack .../14-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 198s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 198s Selecting previously unselected package cpp. 198s Preparing to unpack .../15-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 198s Unpacking cpp (4:13.2.0-7ubuntu1) ... 198s Selecting previously unselected package libcc1-0:armhf. 198s Preparing to unpack .../16-libcc1-0_14-20240330-1ubuntu2_armhf.deb ... 198s Unpacking libcc1-0:armhf (14-20240330-1ubuntu2) ... 198s Selecting previously unselected package libgomp1:armhf. 198s Preparing to unpack .../17-libgomp1_14-20240330-1ubuntu2_armhf.deb ... 198s Unpacking libgomp1:armhf (14-20240330-1ubuntu2) ... 198s Selecting previously unselected package libatomic1:armhf. 198s Preparing to unpack .../18-libatomic1_14-20240330-1ubuntu2_armhf.deb ... 198s Unpacking libatomic1:armhf (14-20240330-1ubuntu2) ... 198s Selecting previously unselected package libasan8:armhf. 198s Preparing to unpack .../19-libasan8_14-20240330-1ubuntu2_armhf.deb ... 198s Unpacking libasan8:armhf (14-20240330-1ubuntu2) ... 198s Selecting previously unselected package libubsan1:armhf. 198s Preparing to unpack .../20-libubsan1_14-20240330-1ubuntu2_armhf.deb ... 198s Unpacking libubsan1:armhf (14-20240330-1ubuntu2) ... 198s Selecting previously unselected package libgcc-13-dev:armhf. 198s Preparing to unpack .../21-libgcc-13-dev_13.2.0-23ubuntu3_armhf.deb ... 198s Unpacking libgcc-13-dev:armhf (13.2.0-23ubuntu3) ... 198s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 198s Preparing to unpack .../22-gcc-13-arm-linux-gnueabihf_13.2.0-23ubuntu3_armhf.deb ... 198s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 198s Selecting previously unselected package gcc-13. 198s Preparing to unpack .../23-gcc-13_13.2.0-23ubuntu3_armhf.deb ... 198s Unpacking gcc-13 (13.2.0-23ubuntu3) ... 199s Selecting previously unselected package gcc-arm-linux-gnueabihf. 199s Preparing to unpack .../24-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 199s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 199s Selecting previously unselected package gcc. 199s Preparing to unpack .../25-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 199s Unpacking gcc (4:13.2.0-7ubuntu1) ... 199s Selecting previously unselected package libstdc++-13-dev:armhf. 199s Preparing to unpack .../26-libstdc++-13-dev_13.2.0-23ubuntu3_armhf.deb ... 199s Unpacking libstdc++-13-dev:armhf (13.2.0-23ubuntu3) ... 199s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 199s Preparing to unpack .../27-g++-13-arm-linux-gnueabihf_13.2.0-23ubuntu3_armhf.deb ... 199s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 199s Selecting previously unselected package g++-13. 199s Preparing to unpack .../28-g++-13_13.2.0-23ubuntu3_armhf.deb ... 199s Unpacking g++-13 (13.2.0-23ubuntu3) ... 199s Selecting previously unselected package g++-arm-linux-gnueabihf. 199s Preparing to unpack .../29-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 199s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 199s Selecting previously unselected package g++. 199s Preparing to unpack .../30-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 199s Unpacking g++ (4:13.2.0-7ubuntu1) ... 199s Selecting previously unselected package build-essential. 199s Preparing to unpack .../31-build-essential_12.10ubuntu1_armhf.deb ... 199s Unpacking build-essential (12.10ubuntu1) ... 199s Selecting previously unselected package libdebhelper-perl. 199s Preparing to unpack .../32-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 199s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 199s Selecting previously unselected package libtool. 199s Preparing to unpack .../33-libtool_2.4.7-7_all.deb ... 199s Unpacking libtool (2.4.7-7) ... 199s Selecting previously unselected package dh-autoreconf. 199s Preparing to unpack .../34-dh-autoreconf_20_all.deb ... 199s Unpacking dh-autoreconf (20) ... 199s Selecting previously unselected package libarchive-zip-perl. 200s Preparing to unpack .../35-libarchive-zip-perl_1.68-1_all.deb ... 200s Unpacking libarchive-zip-perl (1.68-1) ... 200s Selecting previously unselected package libsub-override-perl. 200s Preparing to unpack .../36-libsub-override-perl_0.10-1_all.deb ... 200s Unpacking libsub-override-perl (0.10-1) ... 200s Selecting previously unselected package libfile-stripnondeterminism-perl. 200s Preparing to unpack .../37-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 200s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 200s Selecting previously unselected package dh-strip-nondeterminism. 200s Preparing to unpack .../38-dh-strip-nondeterminism_1.13.1-1_all.deb ... 200s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 200s Selecting previously unselected package libdw1t64:armhf. 200s Preparing to unpack .../39-libdw1t64_0.190-1.1build3_armhf.deb ... 200s Unpacking libdw1t64:armhf (0.190-1.1build3) ... 200s Selecting previously unselected package debugedit. 200s Preparing to unpack .../40-debugedit_1%3a5.0-5build2_armhf.deb ... 200s Unpacking debugedit (1:5.0-5build2) ... 200s Selecting previously unselected package dwz. 200s Preparing to unpack .../41-dwz_0.15-1build6_armhf.deb ... 200s Unpacking dwz (0.15-1build6) ... 200s Selecting previously unselected package gettext. 200s Preparing to unpack .../42-gettext_0.21-14ubuntu1_armhf.deb ... 200s Unpacking gettext (0.21-14ubuntu1) ... 200s Selecting previously unselected package intltool-debian. 200s Preparing to unpack .../43-intltool-debian_0.35.0+20060710.6_all.deb ... 200s Unpacking intltool-debian (0.35.0+20060710.6) ... 200s Selecting previously unselected package po-debconf. 200s Preparing to unpack .../44-po-debconf_1.0.21+nmu1_all.deb ... 200s Unpacking po-debconf (1.0.21+nmu1) ... 200s Selecting previously unselected package debhelper. 200s Preparing to unpack .../45-debhelper_13.14.1ubuntu5_all.deb ... 200s Unpacking debhelper (13.14.1ubuntu5) ... 200s Selecting previously unselected package dh-apport. 200s Preparing to unpack .../46-dh-apport_2.28.0-0ubuntu1_all.deb ... 200s Unpacking dh-apport (2.28.0-0ubuntu1) ... 200s Selecting previously unselected package golang-1.22-src. 200s Preparing to unpack .../47-golang-1.22-src_1.22.1-1build1_all.deb ... 200s Unpacking golang-1.22-src (1.22.1-1build1) ... 203s Selecting previously unselected package golang-1.22-go. 203s Preparing to unpack .../48-golang-1.22-go_1.22.1-1build1_armhf.deb ... 203s Unpacking golang-1.22-go (1.22.1-1build1) ... 204s Selecting previously unselected package golang-src. 204s Preparing to unpack .../49-golang-src_2%3a1.22~2build1_all.deb ... 204s Unpacking golang-src (2:1.22~2build1) ... 204s Selecting previously unselected package golang-go:armhf. 204s Preparing to unpack .../50-golang-go_2%3a1.22~2build1_armhf.deb ... 204s Unpacking golang-go:armhf (2:1.22~2build1) ... 204s Selecting previously unselected package dh-golang. 204s Preparing to unpack .../51-dh-golang_1.62_all.deb ... 204s Unpacking dh-golang (1.62) ... 204s Selecting previously unselected package autopkgtest-satdep. 204s Preparing to unpack .../52-1-autopkgtest-satdep.deb ... 204s Unpacking autopkgtest-satdep (0) ... 204s Setting up libarchive-zip-perl (1.68-1) ... 204s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 204s Setting up linux-libc-dev:armhf (6.8.0-20.20) ... 204s Setting up m4 (1.4.19-4) ... 204s Setting up libgomp1:armhf (14-20240330-1ubuntu2) ... 204s Setting up dh-apport (2.28.0-0ubuntu1) ... 204s Setting up libdw1t64:armhf (0.190-1.1build3) ... 204s Setting up autotools-dev (20220109.1) ... 204s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 204s Setting up golang-1.22-src (1.22.1-1build1) ... 204s Setting up libmpc3:armhf (1.3.1-1) ... 204s Setting up libatomic1:armhf (14-20240330-1ubuntu2) ... 204s Setting up autopoint (0.21-14ubuntu1) ... 204s Setting up autoconf (2.71-3) ... 204s Setting up libubsan1:armhf (14-20240330-1ubuntu2) ... 204s Setting up dwz (0.15-1build6) ... 204s Setting up libcrypt-dev:armhf (1:4.4.36-4) ... 204s Setting up libasan8:armhf (14-20240330-1ubuntu2) ... 204s Setting up debugedit (1:5.0-5build2) ... 204s Setting up libsub-override-perl (0.10-1) ... 204s Setting up libisl23:armhf (0.26-3) ... 204s Setting up libc-dev-bin (2.39-0ubuntu8) ... 204s Setting up golang-src (2:1.22~2build1) ... 204s Setting up libcc1-0:armhf (14-20240330-1ubuntu2) ... 204s Setting up automake (1:1.16.5-1.3ubuntu1) ... 204s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 204s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 204s Setting up gettext (0.21-14ubuntu1) ... 204s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 204s Setting up golang-1.22-go (1.22.1-1build1) ... 204s Setting up intltool-debian (0.35.0+20060710.6) ... 204s Setting up dh-strip-nondeterminism (1.13.1-1) ... 204s Setting up libgcc-13-dev:armhf (13.2.0-23ubuntu3) ... 204s Setting up libc6-dev:armhf (2.39-0ubuntu8) ... 204s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 204s Setting up libstdc++-13-dev:armhf (13.2.0-23ubuntu3) ... 204s Setting up cpp-13 (13.2.0-23ubuntu3) ... 204s Setting up golang-go:armhf (2:1.22~2build1) ... 204s Setting up po-debconf (1.0.21+nmu1) ... 204s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 204s Setting up gcc-13 (13.2.0-23ubuntu3) ... 204s Setting up cpp (4:13.2.0-7ubuntu1) ... 204s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 204s Setting up g++-13-arm-linux-gnueabihf (13.2.0-23ubuntu3) ... 204s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 204s Setting up g++-13 (13.2.0-23ubuntu3) ... 204s Setting up libtool (2.4.7-7) ... 204s Setting up gcc (4:13.2.0-7ubuntu1) ... 204s Setting up dh-autoreconf (20) ... 204s Setting up g++ (4:13.2.0-7ubuntu1) ... 204s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 204s Setting up build-essential (12.10ubuntu1) ... 204s Setting up debhelper (13.14.1ubuntu5) ... 204s Setting up dh-golang (1.62) ... 204s Setting up autopkgtest-satdep (0) ... 204s Processing triggers for man-db (2.12.0-4build1) ... 205s Processing triggers for install-info (7.1-3build2) ... 205s Processing triggers for libc-bin (2.39-0ubuntu8) ... 215s (Reading database ... 76605 files and directories currently installed.) 215s Removing autopkgtest-satdep (0) ... 220s autopkgtest [18:41:24]: test command1: ./debian/tests/test 220s autopkgtest [18:41:24]: test command1: [----------------------- 246s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 246s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 247s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 248s === RUN TestRunSignal 248s === RUN TestRunSignal/Send_SIGINT_exits 248s INFO Starting WSL Pro Service version Dev 248s === RUN TestRunSignal/Send_SIGTERM_exits 248s INFO Starting WSL Pro Service version Dev 248s --- PASS: TestRunSignal (0.25s) 248s --- PASS: TestRunSignal/Send_SIGINT_exits (0.11s) 248s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.11s) 248s === RUN TestRun 248s === PAUSE TestRun 248s === CONT TestRun 248s === RUN TestRun/Run_and_exit_successfully 248s === PAUSE TestRun/Run_and_exit_successfully 248s === RUN TestRun/Run_and_return_error 248s === PAUSE TestRun/Run_and_return_error 248s === RUN TestRun/Run_and_return_usage_error 248s === PAUSE TestRun/Run_and_return_usage_error 248s === RUN TestRun/Run_and_usage_error_only_does_not_fail 248s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 248s === CONT TestRun/Run_and_exit_successfully 248s === CONT TestRun/Run_and_return_usage_error 248s === CONT TestRun/Run_and_usage_error_only_does_not_fail 248s === CONT TestRun/Run_and_return_error 248s INFO Starting WSL Pro Service version Dev 248s INFO Starting WSL Pro Service version Dev 248s INFO Starting WSL Pro Service version Dev 248s INFO Starting WSL Pro Service version Dev 248s ERROR context.Background Error requested 248s ERROR context.Background Error requested 248s --- PASS: TestRun (0.00s) 248s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.16s) 248s --- PASS: TestRun/Run_and_exit_successfully (0.16s) 248s --- PASS: TestRun/Run_and_return_usage_error (0.21s) 248s --- PASS: TestRun/Run_and_return_error (0.21s) 248s PASS 248s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 2.209s 313s === RUN TestHelp 313s --- PASS: TestHelp (4.03s) 313s === RUN TestCompletion 313s --- PASS: TestCompletion (1.68s) 313s === RUN TestVersion 313s --- PASS: TestVersion (0.39s) 313s === RUN TestNoUsageError 313s --- PASS: TestNoUsageError (0.51s) 313s === RUN TestUsageError 313s === PAUSE TestUsageError 313s === RUN TestCanQuitWhenExecute 313s === PAUSE TestCanQuitWhenExecute 313s === RUN TestCanQuitTwice 313s === PAUSE TestCanQuitTwice 313s === RUN TestAppCanQuitWithoutExecute 313s === PAUSE TestAppCanQuitWithoutExecute 313s === RUN TestAppRunFailsOnComponentsCreationAndQuit 313s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 313s === RUN TestAppGetRootCmd 313s === PAUSE TestAppGetRootCmd 313s === RUN TestWithProMock 313s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 313s --- SKIP: TestWithProMock (0.00s) 313s === RUN TestWithWslPathMock 313s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 313s --- SKIP: TestWithWslPathMock (0.00s) 313s === RUN TestWithWslInfoMock 313s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 313s --- SKIP: TestWithWslInfoMock (0.00s) 313s === RUN TestWithCmdExeMock 313s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 313s --- SKIP: TestWithCmdExeMock (0.00s) 313s === CONT TestUsageError 313s --- PASS: TestUsageError (0.00s) 313s === CONT TestAppGetRootCmd 313s === CONT TestAppCanQuitWithoutExecute 313s service_test.go:137: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 313s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 313s === CONT TestAppRunFailsOnComponentsCreationAndQuit 313s === CONT TestCanQuitTwice 313s === CONT TestCanQuitWhenExecute 313s time="2024-04-05T18:41:59Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 313s time="2024-04-05T18:41:59Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:63 New() Building new daemon" 313s time="2024-04-05T18:41:59Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34803\"" 313s time="2024-04-05T18:41:59Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 313s time="2024-04-05T18:41:59Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:63 New() Building new daemon" 313s --- PASS: TestAppGetRootCmd (1.17s) 313s time="2024-04-05T18:42:30Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:30Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:32Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:32Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:43Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:43Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:43Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (44.32s) 313s time="2024-04-05T18:42:45Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/controlstream/session.go:25 newSession() Connecting to control stream at \"127.0.0.1:43725\"" 313s time="2024-04-05T18:42:45Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/controlstream/controlstream.go:75 (*ControlStream).Connect() Control stream: starting handshake" 313s time="2024-04-05T18:42:45Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/testutils/mock_agent.go:134 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 313s time="2024-04-05T18:42:46Z" level=error msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestCanQuitWhenExecute3069745165/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestCanQuitWhenExecute3069745165/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 313s time="2024-04-05T18:42:46Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s time="2024-04-05T18:42:46Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:46Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:46Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s --- PASS: TestCanQuitWhenExecute (47.80s) 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/testutils/mock_agent.go:148 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/testutils/mock_agent.go:176 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 45341" 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/controlstream/controlstream.go:82 (*ControlStream).Connect() Control stream: completed handshake" 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/wslinstanceservice/wslinstanceservice.go:44 (*Service).RegisterGRPCService() Registering gRPC WSL instance service" 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve gRPC requests" 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving gRPC requests on localhost:45341" 313s time="2024-04-05T18:42:57Z" level=error msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 313s time="2024-04-05T18:42:57Z" level=info msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 313s time="2024-04-05T18:42:57Z" level=debug msg="/tmp/autopkgtest.jFtph0/build.4ti/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 313s --- PASS: TestCanQuitTwice (58.43s) 313s PASS 313s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 66.653s 329s === RUN TestNew 329s === PAUSE TestNew 329s === RUN TestConnect 329s === PAUSE TestConnect 329s === RUN TestSend 329s === PAUSE TestSend 329s === RUN TestReconnection 329s === PAUSE TestReconnection 329s === RUN TestWithProMock 329s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 329s --- SKIP: TestWithProMock (0.00s) 329s === RUN TestWithWslPathMock 329s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 329s --- SKIP: TestWithWslPathMock (0.00s) 329s === RUN TestWithWslInfoMock 329s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 329s --- SKIP: TestWithWslInfoMock (0.00s) 329s === RUN TestWithCmdExeMock 329s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 329s --- SKIP: TestWithCmdExeMock (0.00s) 329s === CONT TestNew 329s === CONT TestSend 329s === RUN TestNew/Success 329s === PAUSE TestNew/Success 329s === RUN TestNew/Error_when_the_context_is_cancelled 329s === PAUSE TestNew/Error_when_the_context_is_cancelled 329s === RUN TestNew/Error_when_WslPath_returns_error 329s === PAUSE TestNew/Error_when_WslPath_returns_error 329s === CONT TestNew/Success 329s === CONT TestReconnection 329s === RUN TestReconnection/Success_connecting_after_failing_to_connect 329s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 329s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 329s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 329s === CONT TestReconnection/Success_connecting_after_failing_to_connect 329s === CONT TestConnect 329s === RUN TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 329s === PAUSE TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 329s === RUN TestConnect/Success 329s === PAUSE TestConnect/Success 329s === RUN TestConnect/No_connection_because_port_file_does_not_exist 329s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 329s === RUN TestConnect/No_connection_because_of_unreadable_port_file 329s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 329s === RUN TestConnect/No_connection_because_of_empty_port_file 329s === PAUSE TestConnect/No_connection_because_of_empty_port_file 329s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 329s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 329s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 329s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 329s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 329s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 329s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 329s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 329s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 329s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 329s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 329s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 329s === CONT TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 329s time="2024-04-05T18:41:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37207\"" 329s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 329s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 329s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 329s time="2024-04-05T18:41:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36999\"" 329s === CONT TestNew/Error_when_WslPath_returns_error 329s time="2024-04-05T18:41:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33531\"" 329s time="2024-04-05T18:41:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41969\"" 329s time="2024-04-05T18:42:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42357\"" 329s === CONT TestConnect/No_connection_because_of_empty_port_file 329s time="2024-04-05T18:42:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40561\"" 329s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 329s time="2024-04-05T18:42:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33681\"" 329s time="2024-04-05T18:42:24Z" level=info msg="Connecting to control stream at \"127.0.0.1:42357\"" 329s time="2024-04-05T18:42:24Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:24Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:27Z" level=info msg="Connecting to control stream at \"127.0.0.1:36999\"" 329s time="2024-04-05T18:42:27Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:27Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:28Z" level=info msg="Connecting to control stream at \"127.0.0.1:33531\"" 329s time="2024-04-05T18:42:28Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:28Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:28Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44261\"" 329s time="2024-04-05T18:42:29Z" level=info msg="Connecting to control stream at \"127.0.0.1:37207\"" 329s time="2024-04-05T18:42:29Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:29Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:37Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:37Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44431" 329s time="2024-04-05T18:42:37Z" level=debug msg="Control stream: completed handshake" 329s time="2024-04-05T18:42:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42423\"" 329s time="2024-04-05T18:42:40Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:40Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 329s time="2024-04-05T18:42:40Z" level=info msg="wslInstanceMockService: dropped connection" 329s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 329s time="2024-04-05T18:42:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40407\"" 329s time="2024-04-05T18:42:42Z" level=warning msg="Controlstream: assigning arbitrary connection ID because of error: could not obtain WSL distro name: could not get distro root path: exit status 1. Stdout: " 329s time="2024-04-05T18:42:42Z" level=info msg="Connecting to control stream at \"127.0.0.1:41969\"" 329s time="2024-04-05T18:42:42Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:42Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 329s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 329s time="2024-04-05T18:42:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37849\"" 329s time="2024-04-05T18:42:44Z" level=info msg="Connecting to control stream at \"127.0.0.1:44261\"" 329s time="2024-04-05T18:42:44Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 33251" 329s time="2024-04-05T18:42:44Z" level=debug msg="Control stream: completed handshake" 329s time="2024-04-05T18:42:46Z" level=info msg="Connecting to control stream at \"127.0.0.1:42423\"" 329s time="2024-04-05T18:42:46Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:42:46Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:42:49Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 329s time="2024-04-05T18:42:49Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:49Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 329s time="2024-04-05T18:42:49Z" level=info msg="wslInstanceMockService: dropped connection" 329s --- PASS: TestSend (59.72s) 329s === CONT TestConnect/No_connection_because_port_file_does_not_exist 329s time="2024-04-05T18:42:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35675\"" 329s time="2024-04-05T18:42:51Z" level=warning msg="wslInstanceMockService: dropped connection: new connection: did not receive info from WSL distro: rpc error: code = Canceled desc = context canceled" 329s === CONT TestConnect/No_connection_because_of_unreadable_port_file 329s time="2024-04-05T18:42:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38935\"" 329s === CONT TestConnect/Success 329s time="2024-04-05T18:42:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45301\"" 329s time="2024-04-05T18:42:53Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:53Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 45785" 329s time="2024-04-05T18:42:53Z" level=debug msg="Control stream: completed handshake" 329s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 329s time="2024-04-05T18:42:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45979\"" 329s === CONT TestNew/Error_when_the_context_is_cancelled 329s --- PASS: TestNew (0.00s) 329s --- PASS: TestNew/Success (26.01s) 329s --- PASS: TestNew/Error_when_WslPath_returns_error (27.44s) 329s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.00s) 329s time="2024-04-05T18:42:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:42:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 43861" 329s time="2024-04-05T18:42:55Z" level=debug msg="Control stream: completed handshake" 329s --- PASS: TestReconnection (0.00s) 329s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (63.27s) 329s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (65.19s) 329s time="2024-04-05T18:43:02Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:44431: connect: connection refused" 329s time="2024-04-05T18:43:04Z" level=info msg="Connecting to control stream at \"127.0.0.1:35639\"" 329s time="2024-04-05T18:43:07Z" level=info msg="Connecting to control stream at \"127.0.0.1:37849\"" 329s time="2024-04-05T18:43:07Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:43:07Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:43:07Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 329s time="2024-04-05T18:43:07Z" level=info msg="wslInstanceMockService: dropped connection" 329s time="2024-04-05T18:43:09Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:0: connect: connection refused" 329s time="2024-04-05T18:43:10Z" level=info msg="Connecting to control stream at \"127.0.0.1:45301\"" 329s time="2024-04-05T18:43:10Z" level=debug msg="Control stream: starting handshake" 329s time="2024-04-05T18:43:10Z" level=info msg="wslInstanceMockService: Received incoming connection" 329s time="2024-04-05T18:43:11Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist323107852/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 329s time="2024-04-05T18:43:12Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 329s time="2024-04-05T18:43:12Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 39947" 329s time="2024-04-05T18:43:12Z" level=debug msg="Control stream: completed handshake" 329s --- PASS: TestConnect (0.00s) 329s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (49.92s) 329s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (53.78s) 329s --- PASS: TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name (61.37s) 329s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (34.25s) 329s --- PASS: TestConnect/No_connection_because_of_empty_port_file (37.54s) 329s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (23.37s) 329s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (20.87s) 329s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (27.14s) 329s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (19.65s) 329s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (17.82s) 329s --- PASS: TestConnect/Success (20.91s) 329s PASS 329s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 82.824s 336s === RUN TestNew 336s === PAUSE TestNew 336s === RUN TestServe 336s === PAUSE TestServe 336s === RUN TestServeAndQuit 336s === PAUSE TestServeAndQuit 336s === RUN TestReconnection 336s === PAUSE TestReconnection 336s === RUN TestWithProMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithProMock (0.00s) 336s === RUN TestWithWslPathMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslPathMock (0.00s) 336s === RUN TestWithWslInfoMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslInfoMock (0.00s) 336s === RUN TestWithCmdExeMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithCmdExeMock (0.00s) 336s === CONT TestNew 336s === RUN TestNew/Success 336s === PAUSE TestNew/Success 336s === RUN TestNew/Error_when_WslPath_returns_error 336s === PAUSE TestNew/Error_when_WslPath_returns_error 336s === CONT TestNew/Success 336s === CONT TestServeAndQuit 336s === RUN TestServeAndQuit/Success_with_graceful_quit 336s === PAUSE TestServeAndQuit/Success_with_graceful_quit 336s === RUN TestServeAndQuit/Success_with_forceful_quit 336s === PAUSE TestServeAndQuit/Success_with_forceful_quit 336s === RUN TestServeAndQuit/Success_with_double_quit 336s === PAUSE TestServeAndQuit/Success_with_double_quit 336s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 336s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 336s === CONT TestServeAndQuit/Success_with_graceful_quit 336s === CONT TestServe 336s === RUN TestServe/No_connection_because_of_faulty_agent 336s === PAUSE TestServe/No_connection_because_of_faulty_agent 336s === RUN TestServe/Error_because_of_notifier_returning_error 336s === PAUSE TestServe/Error_because_of_notifier_returning_error 336s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 336s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 336s === RUN TestServe/Error_because_of_context_cancelled 336s === PAUSE TestServe/Error_because_of_context_cancelled 336s === RUN TestServe/Success 336s === PAUSE TestServe/Success 336s === RUN TestServe/Success_with_systemd_notifier_returning_true 336s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 336s === RUN TestServe/No_connection_because_port_file_does_not_exist 336s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 336s === CONT TestServe/No_connection_because_of_faulty_agent 336s === CONT TestReconnection 336s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 336s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 336s === RUN TestReconnection/Success_connecting_after_failing_to_connect 336s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 336s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 336s === CONT TestNew/Error_when_WslPath_returns_error 336s === CONT TestServeAndQuit/Success_with_double_quit 336s === CONT TestServe/No_connection_because_port_file_does_not_exist 336s === CONT TestServeAndQuit/Success_with_forceful_quit 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:46749\"" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39925\"" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36217\"" 336s time="2024-04-05T18:41:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45985\"" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45153\"" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:41:52Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:20Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:32895\"" 336s time="2024-04-05T18:42:20Z" level=debug msg="Ready state sent to systemd" 336s time="2024-04-05T18:42:25Z" level=debug msg="Ready state sent to systemd" 336s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 336s time="2024-04-05T18:42:26Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:26Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43553\"" 336s --- PASS: TestNew (0.00s) 336s --- PASS: TestNew/Success (34.31s) 336s --- PASS: TestNew/Error_when_WslPath_returns_error (34.60s) 336s === CONT TestServe/Error_because_of_context_cancelled 336s time="2024-04-05T18:42:26Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:26Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:32823\"" 336s time="2024-04-05T18:42:27Z" level=debug msg="Ready state sent to systemd" 336s time="2024-04-05T18:42:29Z" level=debug msg="Ready state sent to systemd" 336s time="2024-04-05T18:42:30Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:31Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:31Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_port_file_does_not_exist327277451/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist327277451/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 336s time="2024-04-05T18:42:31Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist327277451/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 336s === CONT TestServe/Success_with_systemd_notifier_returning_true 336s time="2024-04-05T18:42:32Z" level=info msg="Connecting to control stream at \"127.0.0.1:39925\"" 336s time="2024-04-05T18:42:32Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:32Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:42:32Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 336s time="2024-04-05T18:42:32Z" level=info msg="wslInstanceMockService: dropped connection" 336s time="2024-04-05T18:42:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36955\"" 336s time="2024-04-05T18:42:32Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:34Z" level=info msg="Connecting to control stream at \"127.0.0.1:32895\"" 336s time="2024-04-05T18:42:34Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:34Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:42:38Z" level=info msg="Connecting to control stream at \"127.0.0.1:45985\"" 336s time="2024-04-05T18:42:38Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:38Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:42:41Z" level=info msg="Connecting to control stream at \"127.0.0.1:46749\"" 336s time="2024-04-05T18:42:41Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:41Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:42:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37765" 336s time="2024-04-05T18:42:44Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:42:44Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:42:44Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:42:44Z" level=info msg="Serving gRPC requests on localhost:37765" 336s time="2024-04-05T18:42:44Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:42:45Z" level=error msg="Serve error: lost connection to Windows Agent" 336s time="2024-04-05T18:42:45Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 336s time="2024-04-05T18:42:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33179\"" 336s time="2024-04-05T18:42:45Z" level=info msg="Connecting to control stream at \"127.0.0.1:45153\"" 336s time="2024-04-05T18:42:45Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:45Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:42:45Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 336s === CONT TestServe/Success 336s time="2024-04-05T18:42:46Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40443\"" 336s time="2024-04-05T18:42:46Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:42:46Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34251" 336s time="2024-04-05T18:42:46Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:42:46Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:42:46Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:42:46Z" level=info msg="Serving gRPC requests on localhost:34251" 336s time="2024-04-05T18:42:46Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:42:47Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:47Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s time="2024-04-05T18:42:47Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s === CONT TestReconnection/Success_connecting_after_failing_to_connect 336s time="2024-04-05T18:42:47Z" level=debug msg="Building new daemon" 336s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 336s time="2024-04-05T18:42:47Z" level=info msg="Retrying connection to control stream" 336s time="2024-04-05T18:42:47Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 336s time="2024-04-05T18:42:47Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39287\"" 336s time="2024-04-05T18:42:47Z" level=debug msg="Building new daemon" 336s === CONT TestServe/Error_because_of_notifier_returning_error 336s time="2024-04-05T18:42:49Z" level=debug msg="Building new daemon" 336s time="2024-04-05T18:42:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39631\"" 336s time="2024-04-05T18:42:50Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:42:50Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34113" 336s time="2024-04-05T18:42:50Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:42:50Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:42:50Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:42:50Z" level=info msg="Serving gRPC requests on localhost:34113" 336s time="2024-04-05T18:42:50Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:42:50Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:50Z" level=info msg="Waiting for active requests to close." 336s time="2024-04-05T18:42:50Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s time="2024-04-05T18:42:50Z" level=debug msg="All connections have now ended." 336s time="2024-04-05T18:42:56Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:42:56Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37069" 336s time="2024-04-05T18:42:56Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:42:56Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:42:56Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:42:56Z" level=info msg="Serving gRPC requests on localhost:37069" 336s time="2024-04-05T18:42:56Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:42:56Z" level=debug msg="Ready state sent to systemd" 336s time="2024-04-05T18:42:56Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:56Z" level=info msg="Waiting for active requests to close." 336s time="2024-04-05T18:42:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s time="2024-04-05T18:42:56Z" level=debug msg="All connections have now ended." 336s time="2024-04-05T18:42:56Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:42:56Z" level=info msg="Waiting for active requests to close." 336s time="2024-04-05T18:42:56Z" level=debug msg="All connections have now ended." 336s --- PASS: TestServeAndQuit (0.00s) 336s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (20.92s) 336s --- PASS: TestServeAndQuit/Success_with_forceful_quit (55.40s) 336s --- PASS: TestServeAndQuit/Success_with_graceful_quit (59.87s) 336s --- PASS: TestServeAndQuit/Success_with_double_quit (64.63s) 336s time="2024-04-05T18:42:57Z" level=info msg="Connecting to control stream at \"127.0.0.1:33179\"" 336s time="2024-04-05T18:42:57Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:42:57Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:43:04Z" level=info msg="Connecting to control stream at \"127.0.0.1:36955\"" 336s time="2024-04-05T18:43:04Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:43:04Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:43:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:43:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 43479" 336s time="2024-04-05T18:43:04Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:43:04Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:43:04Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:43:04Z" level=info msg="Serving gRPC requests on localhost:43479" 336s time="2024-04-05T18:43:04Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:43:05Z" level=debug msg="Ready state sent to systemd" 336s time="2024-04-05T18:43:06Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:43:08Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 336s time="2024-04-05T18:43:08Z" level=info msg="Connecting to control stream at \"127.0.0.1:40443\"" 336s time="2024-04-05T18:43:08Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:43:08Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:43:09Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:37765: connect: connection refused" 336s time="2024-04-05T18:43:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:43:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40221" 336s time="2024-04-05T18:43:09Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:43:09Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:43:09Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:43:09Z" level=info msg="Serving gRPC requests on localhost:40221" 336s time="2024-04-05T18:43:09Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:43:09Z" level=error msg="Serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 336s time="2024-04-05T18:43:09Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 336s time="2024-04-05T18:43:09Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s time="2024-04-05T18:43:09Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_failing_to_connect3494484665/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect3494484665/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 336s time="2024-04-05T18:43:09Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 336s time="2024-04-05T18:43:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 336s time="2024-04-05T18:43:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39647\"" 336s time="2024-04-05T18:43:10Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:43:10Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 336s time="2024-04-05T18:43:10Z" level=info msg="wslInstanceMockService: dropped connection" 336s time="2024-04-05T18:43:10Z" level=error msg="Serve error: lost connection to Windows Agent" 336s time="2024-04-05T18:43:10Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 336s time="2024-04-05T18:43:10Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s time="2024-04-05T18:43:11Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:34251: connect: connection refused" 336s time="2024-04-05T18:43:11Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:43:11Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 41129" 336s time="2024-04-05T18:43:11Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:43:11Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:43:11Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:43:11Z" level=info msg="Serving gRPC requests on localhost:41129" 336s time="2024-04-05T18:43:11Z" level=info msg="Retrying connection to control stream" 336s time="2024-04-05T18:43:11Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 336s time="2024-04-05T18:43:13Z" level=info msg="Connecting to control stream at \"127.0.0.1:39647\"" 336s time="2024-04-05T18:43:13Z" level=debug msg="Control stream: starting handshake" 336s time="2024-04-05T18:43:13Z" level=info msg="wslInstanceMockService: Received incoming connection" 336s time="2024-04-05T18:43:13Z" level=info msg="Stopping daemon requested." 336s --- PASS: TestServe (0.00s) 336s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (40.26s) 336s --- PASS: TestServe/No_connection_because_of_faulty_agent (54.06s) 336s --- PASS: TestServe/Error_because_of_context_cancelled (22.30s) 336s --- PASS: TestServe/Error_because_of_notifier_returning_error (19.40s) 336s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (37.59s) 336s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (23.01s) 336s --- PASS: TestServe/Success (27.60s) 336s time="2024-04-05T18:43:14Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 336s time="2024-04-05T18:43:14Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37837" 336s time="2024-04-05T18:43:14Z" level=debug msg="Control stream: completed handshake" 336s time="2024-04-05T18:43:14Z" level=info msg="Connected to control stream" 336s time="2024-04-05T18:43:14Z" level=debug msg="Starting to serve gRPC requests" 336s time="2024-04-05T18:43:14Z" level=info msg="Serving gRPC requests on localhost:37837" 336s time="2024-04-05T18:43:14Z" level=debug msg="Updated systemd status to \"Serving\"" 336s time="2024-04-05T18:43:15Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:43:15Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:34113: connect: connection refused" 336s time="2024-04-05T18:43:18Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:43:19Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 336s time="2024-04-05T18:43:20Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 336s time="2024-04-05T18:43:20Z" level=info msg="wslInstanceMockService: dropped connection" 336s time="2024-04-05T18:43:20Z" level=error msg="Serve error: lost connection to Windows Agent" 336s time="2024-04-05T18:43:20Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 336s time="2024-04-05T18:43:20Z" level=info msg="Stopping daemon requested." 336s time="2024-04-05T18:43:20Z" level=debug msg="Updated systemd status to \"Stopped\"" 336s --- PASS: TestReconnection (0.00s) 336s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (78.60s) 336s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (32.75s) 336s PASS 336s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 89.437s 336s === RUN TestInfo 336s === PAUSE TestInfo 336s === RUN TestWslDistroName 336s === PAUSE TestWslDistroName 336s === RUN TestUserProfileDir 336s === PAUSE TestUserProfileDir 336s === RUN TestProStatus 336s === PAUSE TestProStatus 336s === RUN TestProAttach 336s === PAUSE TestProAttach 336s === RUN TestProDetach 336s === PAUSE TestProDetach 336s === RUN TestLandscapeEnable 336s === PAUSE TestLandscapeEnable 336s === RUN TestWindowsHostAddress 336s === PAUSE TestWindowsHostAddress 336s === RUN TestLandscapeDisable 336s === PAUSE TestLandscapeDisable 336s === RUN TestWithProMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithProMock (0.00s) 336s === RUN TestWithLandscapeConfigMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithLandscapeConfigMock (0.00s) 336s === RUN TestWithWslPathMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslPathMock (0.00s) 336s === RUN TestWithWslInfoMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslInfoMock (0.00s) 336s === RUN TestWithCmdExeMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithCmdExeMock (0.00s) 336s === CONT TestInfo 336s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 336s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 336s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 336s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 336s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 336s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 336s === RUN TestInfo/Success 336s === PAUSE TestInfo/Success 336s === RUN TestInfo/Error_when_WslDistroName_fails 336s === PAUSE TestInfo/Error_when_WslDistroName_fails 336s === RUN TestInfo/Error_when_pro_status_command_fails 336s === PAUSE TestInfo/Error_when_pro_status_command_fails 336s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 336s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 336s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 336s === CONT TestUserProfileDir 336s === CONT TestWslDistroName 336s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 336s === CONT TestLandscapeEnable 336s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 336s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 336s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 336s === CONT TestProDetach 336s === RUN TestProDetach/success_on_unattached_distro 336s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 336s === CONT TestProAttach 336s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 336s === PAUSE TestProDetach/success_on_unattached_distro 336s === RUN TestProAttach/success 336s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 336s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 336s === RUN TestProDetach/success_on_attached_distro 336s === CONT TestLandscapeDisable 336s === PAUSE TestProAttach/success 336s === PAUSE TestProDetach/success_on_attached_distro 336s === RUN TestLandscapeDisable/Success 336s === PAUSE TestLandscapeDisable/Success 336s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 336s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 336s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 336s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 336s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 336s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 336s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 336s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 336s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 336s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 336s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 336s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 336s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 336s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 336s === CONT TestProStatus 336s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 336s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 336s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 336s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 336s === RUN TestUserProfileDir/Error_on_cmd.exe_error 336s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 336s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 336s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 336s === RUN TestUserProfileDir/Error_on_wslpath_error 336s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 336s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 336s === RUN TestProStatus/success_on_attached_distro 336s === PAUSE TestUserProfileDir/Error_on_wslpath_error 336s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 336s === PAUSE TestProStatus/success_on_attached_distro 336s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 336s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 336s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 336s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 336s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 336s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 336s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 336s === RUN TestLandscapeEnable/Success 336s === PAUSE TestLandscapeEnable/Success 336s === RUN TestLandscapeEnable/Success_overriding_computer_title 336s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 336s === CONT TestWindowsHostAddress 336s === CONT TestInfo/Error_when_pro_status_command_fails 336s === RUN TestWslDistroName/Success_using_wslpath 336s === PAUSE TestWslDistroName/Success_using_wslpath 336s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 336s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 336s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 336s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 336s === RUN TestWindowsHostAddress/Success_without_NAT 336s === PAUSE TestWindowsHostAddress/Success_without_NAT 336s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 336s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 336s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 336s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 336s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 336s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 336s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 336s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 336s === CONT TestInfo/Success 336s === RUN TestProAttach/error_on_'pro_attach'_error 336s === PAUSE TestProAttach/error_on_'pro_attach'_error 336s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 336s === CONT TestInfo/Error_when_WslDistroName_fails 336s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 336s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 336s === RUN TestProStatus/error_on_'pro_attach'_error 336s === PAUSE TestProStatus/error_on_'pro_attach'_error 336s === RUN TestProStatus/success_on_unattached_distro 336s === PAUSE TestProStatus/success_on_unattached_distro 336s === CONT TestProDetach/success_on_unattached_distro 336s === CONT TestLandscapeDisable/Success 336s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 336s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 336s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 336s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 336s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 336s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 336s === CONT TestUserProfileDir/Error_on_wslpath_error 336s --- PASS: TestInfo (0.00s) 336s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (15.81s) 336s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (18.25s) 336s --- PASS: TestInfo/Success (18.97s) 336s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (20.48s) 336s --- PASS: TestInfo/Error_when_pro_status_command_fails (20.62s) 336s --- PASS: TestInfo/Error_when_WslDistroName_fails (20.84s) 336s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (23.54s) 336s === CONT TestUserProfileDir/Error_on_cmd.exe_error 336s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 336s system_test.go:209: Removing default proc/mounts 336s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 336s system_test.go:209: Removing default proc/mounts 336s === CONT TestLandscapeEnable/Success_overriding_computer_title 336s time="2024-04-05T18:42:22Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 336s --- PASS: TestLandscapeDisable (0.00s) 336s --- PASS: TestLandscapeDisable/Success (14.82s) 336s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (15.14s) 336s === CONT TestLandscapeEnable/Success 336s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 336s === NAME TestLandscapeEnable/Success_overriding_computer_title 336s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 336s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 336s === NAME TestLandscapeEnable/Success 336s system_test.go:456: testdata/TestLandscapeEnable/golden/success 336s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 336s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 336s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 336s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 336s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 336s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 336s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 336s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 336s === CONT TestProDetach/success_on_attached_distro 336s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 336s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 336s --- PASS: TestLandscapeEnable (0.00s) 336s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (13.43s) 336s --- PASS: TestLandscapeEnable/Success (14.15s) 336s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.16s) 336s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.04s) 336s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (15.65s) 336s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (33.31s) 336s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (12.36s) 336s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 336s === CONT TestWslDistroName/Success_using_wslpath 336s === CONT TestProAttach/success 336s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 336s --- PASS: TestProDetach (0.00s) 336s --- PASS: TestProDetach/success_on_unattached_distro (22.75s) 336s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (9.95s) 336s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (9.29s) 336s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (11.08s) 336s --- PASS: TestProDetach/success_on_attached_distro (10.23s) 336s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 336s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 336s === CONT TestWindowsHostAddress/Success_without_NAT 336s --- PASS: TestUserProfileDir (0.00s) 336s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (1.73s) 336s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.06s) 336s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.01s) 336s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (13.29s) 336s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (26.81s) 336s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (30.22s) 336s --- PASS: TestUserProfileDir/Error_on_wslpath_error (27.70s) 336s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (30.96s) 336s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (19.22s) 336s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (19.12s) 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 336s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 336s === CONT TestProAttach/error_on_'pro_attach'_error 336s === CONT TestProStatus/success_on_attached_distro 336s === CONT TestProStatus/success_on_unattached_distro 336s === CONT TestProStatus/error_on_'pro_attach'_error 336s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 336s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 336s --- PASS: TestWindowsHostAddress (0.00s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (9.30s) 336s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (7.75s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (7.61s) 336s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (8.25s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (7.71s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (7.14s) 336s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (8.13s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (7.54s) 336s --- PASS: TestWindowsHostAddress/Success_without_NAT (8.00s) 336s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (6.57s) 336s --- PASS: TestProAttach (0.00s) 336s --- PASS: TestProAttach/success (8.93s) 336s --- PASS: TestProAttach/error_on_'pro_attach'_error (7.62s) 336s --- PASS: TestProStatus (0.00s) 336s --- PASS: TestProStatus/success_on_attached_distro (6.07s) 336s --- PASS: TestProStatus/success_on_unattached_distro (4.72s) 336s --- PASS: TestProStatus/error_on_'pro_attach'_error (4.41s) 336s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (4.03s) 336s --- PASS: TestWslDistroName (0.00s) 336s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.16s) 336s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (9.32s) 336s --- PASS: TestWslDistroName/Success_using_wslpath (8.22s) 336s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (3.39s) 336s PASS 336s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 80.219s 336s === RUN TestApplyProToken 336s === PAUSE TestApplyProToken 336s === RUN TestApplyLandscapeConfig 336s === PAUSE TestApplyLandscapeConfig 336s === RUN TestWithProMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithProMock (0.00s) 336s === RUN TestWithLandscapeConfigMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithLandscapeConfigMock (0.00s) 336s === RUN TestWithWslPathMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslPathMock (0.00s) 336s === RUN TestWithWslInfoMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithWslInfoMock (0.00s) 336s === RUN TestWithCmdExeMock 336s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 336s --- SKIP: TestWithCmdExeMock (0.00s) 336s === CONT TestApplyProToken 336s === RUN TestApplyProToken/success_attaching_non-attached_machine 336s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 336s === RUN TestApplyProToken/Error_calling_pro_attach 336s === PAUSE TestApplyProToken/Error_calling_pro_attach 336s === RUN TestApplyProToken/Error_detaching_pro 336s === PAUSE TestApplyProToken/Error_detaching_pro 336s === RUN TestApplyProToken/Error_calling_pro_status 336s === PAUSE TestApplyProToken/Error_calling_pro_status 336s === RUN TestApplyProToken/Error_getting_system_info 336s === PAUSE TestApplyProToken/Error_getting_system_info 336s === RUN TestApplyProToken/success_attaching_attached_machine 336s === PAUSE TestApplyProToken/success_attaching_attached_machine 336s === RUN TestApplyProToken/success_detaching_attached_machine 336s === PAUSE TestApplyProToken/success_detaching_attached_machine 336s === RUN TestApplyProToken/success_detaching_non-attached_machine 336s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 336s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 336s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 336s === CONT TestApplyProToken/success_attaching_non-attached_machine 336s === CONT TestApplyProToken/success_attaching_attached_machine 336s === CONT TestApplyProToken/Error_calling_pro_status 336s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 336s === CONT TestApplyProToken/success_detaching_non-attached_machine 336s === CONT TestApplyProToken/success_detaching_attached_machine 336s === CONT TestApplyLandscapeConfig 336s === CONT TestApplyProToken/Error_getting_system_info 336s === RUN TestApplyLandscapeConfig/Success_enabling 336s === PAUSE TestApplyLandscapeConfig/Success_enabling 336s === RUN TestApplyLandscapeConfig/Success_disabling 336s === PAUSE TestApplyLandscapeConfig/Success_disabling 336s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 336s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 336s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 336s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 336s === CONT TestApplyLandscapeConfig/Success_enabling 336s time="2024-04-05T18:41:52Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/success_attaching_non-attached_machine 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36659 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:52Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 336s time="2024-04-05T18:41:52Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/Error_cannot_send_info_to_stream 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34073 336s time="2024-04-05T18:41:52Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/success_attaching_attached_machine 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:43705 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s === NAME TestApplyProToken/Error_cannot_send_info_to_stream 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s time="2024-04-05T18:41:53Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyLandscapeConfig/Success_enabling 336s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:42505 336s wslinstanceservice_test.go:167: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyLandscapeConfig: received config: registering" 336s time="2024-04-05T18:41:53Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/success_detaching_attached_machine 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:33815 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s time="2024-04-05T18:41:53Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/success_detaching_non-attached_machine 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36655 336s time="2024-04-05T18:41:53Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/Error_calling_pro_status 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:35249 336s === NAME TestApplyProToken/success_detaching_non-attached_machine 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s === NAME TestApplyProToken/Error_calling_pro_status 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s time="2024-04-05T18:41:53Z" level=debug msg="Registering gRPC WSL instance service" 336s === NAME TestApplyProToken/Error_getting_system_info 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39805 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:41:53Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s === NAME TestApplyLandscapeConfig/Success_enabling 336s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 336s === CONT TestApplyProToken/Error_detaching_pro 336s time="2024-04-05T18:42:11Z" level=debug msg="Registering gRPC WSL instance service" 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:37185 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:42:11Z" level=info msg="ApplyProToken: Received empty token: detaching" 336s time="2024-04-05T18:42:21Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not read /etc/os-release: open /tmp/TestApplyProTokenError_getting_system_info1594290809/001/etc/os-release: no such file or directory" 336s === CONT TestApplyProToken/Error_calling_pro_attach 336s time="2024-04-05T18:42:22Z" level=debug msg="Registering gRPC WSL instance service" 336s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:44869 336s wslinstanceservice_test.go:108: Client connected to WslInstanceService 336s time="2024-04-05T18:42:22Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 336s time="2024-04-05T18:42:24Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not obtain pro status: pro status: command returned error: exit status 1\nStdout:" 336s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 336s time="2024-04-05T18:42:24Z" level=debug msg="Registering gRPC WSL instance service" 336s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:44461 336s wslinstanceservice_test.go:167: Client connected to WslInstanceService 336s time="2024-04-05T18:42:24Z" level=info msg="ApplyLandscapeConfig: received config: registering" 336s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 336s time="2024-04-05T18:42:25Z" level=debug msg="Registering gRPC WSL instance service" 336s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:39625 336s wslinstanceservice_test.go:167: Client connected to WslInstanceService 336s time="2024-04-05T18:42:25Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 336s === CONT TestApplyLandscapeConfig/Success_disabling 336s time="2024-04-05T18:42:27Z" level=debug msg="Registering gRPC WSL instance service" 336s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:42185 336s wslinstanceservice_test.go:167: Client connected to WslInstanceService 336s time="2024-04-05T18:42:27Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 336s time="2024-04-05T18:42:28Z" level=warning msg="ApplyProToken: could not send update via control stream: could not send system info: test error" 336s --- PASS: TestApplyLandscapeConfig (0.00s) 336s --- PASS: TestApplyLandscapeConfig/Success_enabling (18.72s) 336s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (12.93s) 336s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (12.82s) 336s --- PASS: TestApplyLandscapeConfig/Success_disabling (13.59s) 336s --- PASS: TestApplyProToken (0.00s) 336s --- PASS: TestApplyProToken/Error_getting_system_info (30.35s) 336s --- PASS: TestApplyProToken/Error_calling_pro_status (32.36s) 336s --- PASS: TestApplyProToken/success_detaching_attached_machine (33.27s) 336s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (35.36s) 336s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (35.65s) 336s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (42.32s) 336s --- PASS: TestApplyProToken/success_attaching_attached_machine (46.68s) 336s --- PASS: TestApplyProToken/Error_detaching_pro (29.38s) 336s --- PASS: TestApplyProToken/Error_calling_pro_attach (33.55s) 336s PASS 336s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 64.776s 337s autopkgtest [18:43:21]: test command1: -----------------------] 340s autopkgtest [18:43:24]: test command1: - - - - - - - - - - results - - - - - - - - - - 340s command1 PASS 344s autopkgtest [18:43:28]: @@@@@@@@@@@@@@@@@@@@ summary 344s command1 PASS