0s autopkgtest [22:02:25]: starting date and time: 2025-03-15 22:02:25+0000 0s autopkgtest [22:02:25]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [22:02:25]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dk0nv5_w/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade wsl-pro-service --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-riscv64-29.secgroup --name adt-plucky-riscv64-wsl-pro-service-20250315-220225-juju-7f2275-prod-proposed-migration-environment-15-46ac3463-de29-495d-8275-33cfbbf570e3 --image adt/ubuntu-plucky-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-riscv64 -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 324s autopkgtest [22:07:49]: testbed dpkg architecture: riscv64 327s autopkgtest [22:07:52]: testbed apt version: 2.9.33 330s autopkgtest [22:07:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 332s autopkgtest [22:07:57]: testbed release detected to be: None 340s autopkgtest [22:08:05]: updating testbed package index (apt update) 343s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 344s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 344s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 344s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 345s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [44.1 kB] 346s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 346s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 346s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 Packages [72.7 kB] 346s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 c-n-f Metadata [1792 B] 346s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted riscv64 c-n-f Metadata [120 B] 346s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 Packages [319 kB] 346s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 c-n-f Metadata [14.3 kB] 346s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 Packages [3532 B] 346s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 c-n-f Metadata [272 B] 360s Fetched 965 kB in 7s (148 kB/s) 384s Reading package lists... 391s + lsb_release --codename --short 392s + RELEASE=plucky 392s + cat 392s + [ plucky != trusty ] 392s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 393s Reading package lists... 396s Building dependency tree... 396s Reading state information... 398s Calculating upgrade... 400s Calculating upgrade... 403s The following packages will be upgraded: 403s pinentry-curses python3-jinja2 strace 404s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 404s Need to get 615 kB of archives. 404s After this operation, 27.6 kB of additional disk space will be used. 404s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 strace riscv64 6.13+ds-1ubuntu1 [460 kB] 404s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 pinentry-curses riscv64 1.3.1-2ubuntu3 [45.4 kB] 404s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 412s Fetched 615 kB in 1s (439 kB/s) 415s (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 ... 114110 files and directories currently installed.) 415s Preparing to unpack .../strace_6.13+ds-1ubuntu1_riscv64.deb ... 415s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 417s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_riscv64.deb ... 417s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 418s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 421s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 422s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 422s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 429s Setting up strace (6.13+ds-1ubuntu1) ... 429s Processing triggers for man-db (2.13.0-1) ... 443s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 443s + /usr/lib/apt/apt-helper analyze-pattern ?true 443s + + uname -r 443s sed s/\./\\./g 443s + running_kernel_pattern=^linux-.*6\.14\.0-7-generic.* 443s + apt list ?obsolete 443s + tail -n+2 443s + cut -d/ -f1 443s + grep -v ^linux-.*6\.14\.0-7-generic.* 448s + obsolete_pkgs=linux-headers-6.11.0-8-generic 448s linux-image-6.11.0-8-generic 448s linux-modules-6.11.0-8-generic 448s linux-riscv-headers-6.11.0-8 448s linux-riscv-tools-6.11.0-8 448s linux-tools-6.11.0-8-generic 448s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-image-6.11.0-8-generic linux-modules-6.11.0-8-generic linux-riscv-headers-6.11.0-8 linux-riscv-tools-6.11.0-8 linux-tools-6.11.0-8-generic 449s Reading package lists... 452s Building dependency tree... 452s Reading state information... 456s Solving dependencies... 458s The following packages will be REMOVED: 458s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 458s linux-headers-6.11.0-8-generic* linux-image-6.11.0-8-generic* 458s linux-modules-6.11.0-8-generic* linux-riscv-headers-6.11.0-8* 458s linux-riscv-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 465s 0 upgraded, 0 newly installed, 10 to remove and 5 not upgraded. 465s After this operation, 355 MB disk space will be freed. 467s (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 ... 114110 files and directories currently installed.) 467s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8.1) ... 467s Removing linux-riscv-tools-6.11.0-8 (6.11.0-8.8.1) ... 467s Removing libpython3.12t64:riscv64 (3.12.9-1) ... 467s Removing libpython3.12-stdlib:riscv64 (3.12.9-1) ... 469s Removing libnsl2:riscv64 (1.3.0-3build3) ... 469s Removing libpython3.12-minimal:riscv64 (3.12.9-1) ... 472s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8.1) ... 479s Removing linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 486s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-7-generic 486s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-7-generic 486s /etc/kernel/postrm.d/initramfs-tools: 486s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 488s /etc/kernel/postrm.d/zz-u-boot-menu: 488s P: Checking for EXTLINUX directory... found. 489s P: Writing config for vmlinuz-6.14.0-7-generic... 489s P: Updating /boot/extlinux/extlinux.conf... 492s /etc/kernel/postrm.d/zz-update-grub: 492s Sourcing file `/etc/default/grub' 492s Generating grub configuration file ... 500s Found linux image: /boot/vmlinuz-6.14.0-7-generic 500s Found initrd image: /boot/initrd.img-6.14.0-7-generic 510s Warning: os-prober will not be executed to detect other bootable partitions. 510s Systems on them will not be added to the GRUB boot configuration. 510s Check GRUB_DISABLE_OS_PROBER documentation entry. 510s Adding boot menu entry for UEFI Firmware Settings ... 511s done 511s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 519s Removing linux-riscv-headers-6.11.0-8 (6.11.0-8.8.1) ... 540s Processing triggers for libc-bin (2.41-1ubuntu1) ... 543s (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 ... 75587 files and directories currently installed.) 543s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8.1) ... 545s /etc/kernel/postrm.d/zz-u-boot-menu: 545s P: Checking for EXTLINUX directory... found. 546s P: Writing config for vmlinuz-6.14.0-7-generic... 549s Purging configuration files for libpython3.12-minimal:riscv64 (3.12.9-1) ... 549s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8.1) ... 558s + grep -q trusty /etc/lsb-release 559s + [ ! -d /usr/share/doc/unattended-upgrades ] 559s + [ ! -d /usr/share/doc/lxd ] 559s + [ ! -d /usr/share/doc/lxd-client ] 559s + [ ! -d /usr/share/doc/snapd ] 559s + type iptables 559s + cat 559s + chmod 755 /etc/rc.local 559s + . /etc/rc.local 559s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 559s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 559s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 559s + uname -m 559s + [ riscv64 = ppc64le ] 559s + [ -d /run/systemd/system ] 559s + systemd-detect-virt --quiet --vm 559s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 559s + cat 559s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 560s + echo COMPRESS=lz4 560s autopkgtest [22:11:45]: upgrading testbed (apt dist-upgrade and autopurge) 563s Reading package lists... 566s Building dependency tree... 566s Reading state information... 569s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 570s Starting 2 pkgProblemResolver with broken count: 0 570s Done 576s Entering ResolveByKeep 578s 579s Calculating upgrade... 580s The following packages will be upgraded: 580s libc-bin libc-dev-bin libc6 libc6-dev locales 581s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 581s Need to get 11.3 MB of archives. 581s After this operation, 2048 B of additional disk space will be used. 581s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6-dev riscv64 2.41-1ubuntu2 [3560 kB] 582s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-dev-bin riscv64 2.41-1ubuntu2 [24.0 kB] 582s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc6 riscv64 2.41-1ubuntu2 [2845 kB] 583s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libc-bin riscv64 2.41-1ubuntu2 [640 kB] 583s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 locales all 2.41-1ubuntu2 [4246 kB] 591s Preconfiguring packages ... 594s Fetched 11.3 MB in 3s (3462 kB/s) 596s (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 ... 75584 files and directories currently installed.) 596s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_riscv64.deb ... 596s Unpacking libc6-dev:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 605s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_riscv64.deb ... 605s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 606s Preparing to unpack .../libc6_2.41-1ubuntu2_riscv64.deb ... 611s Unpacking libc6:riscv64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 615s Setting up libc6:riscv64 (2.41-1ubuntu2) ... 622s (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 ... 75584 files and directories currently installed.) 622s Preparing to unpack .../libc-bin_2.41-1ubuntu2_riscv64.deb ... 622s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 623s Setting up libc-bin (2.41-1ubuntu2) ... 625s (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 ... 75584 files and directories currently installed.) 625s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 625s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 632s Setting up locales (2.41-1ubuntu2) ... 683s Generating locales (this might take a while)... 708s en_US.UTF-8... done 708s Generation complete. 709s Setting up libc-dev-bin (2.41-1ubuntu2) ... 709s Setting up libc6-dev:riscv64 (2.41-1ubuntu2) ... 709s Processing triggers for man-db (2.13.0-1) ... 719s Processing triggers for systemd (257.3-1ubuntu3) ... 746s Reading package lists... 749s Building dependency tree... 750s Reading state information... 753s Starting pkgProblemResolver with broken count: 0 754s Starting 2 pkgProblemResolver with broken count: 0 754s Done 758s Solving dependencies... 760s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 763s autopkgtest [22:15:08]: rebooting testbed after setup commands that affected boot 773s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 806s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 839s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 872s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 905s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 938s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 963s autopkgtest [22:18:28]: testbed running kernel: Linux 6.14.0-7-generic #7.1-Ubuntu SMP PREEMPT_DYNAMIC Sat Mar 1 14:36:03 UTC 2025 986s autopkgtest [22:18:51]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 1009s Get:1 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (dsc) [1422 B] 1009s Get:2 http://ftpmaster.internal/ubuntu plucky/main wsl-pro-service 0.1.5 (tar) [1790 kB] 1013s gpgv: Signature made Tue Sep 10 12:05:15 2024 UTC 1013s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 1013s gpgv: Can't check signature: No public key 1013s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.5.dsc: no acceptable signature found 1019s autopkgtest [22:19:24]: testing package wsl-pro-service version 0.1.5 1021s autopkgtest [22:19:26]: build not needed 1026s autopkgtest [22:19:31]: test command1: preparing testbed 1032s Reading package lists... 1036s Building dependency tree... 1036s Reading state information... 1040s Starting pkgProblemResolver with broken count: 0 1041s Starting 2 pkgProblemResolver with broken count: 0 1041s Done 1046s The following NEW packages will be installed: 1046s autoconf automake autopoint autotools-dev build-essential cpp cpp-14 1046s cpp-14-riscv64-linux-gnu cpp-riscv64-linux-gnu debhelper debugedit dh-apport 1046s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-14 1046s g++-14-riscv64-linux-gnu g++-riscv64-linux-gnu gcc gcc-14 1046s gcc-14-riscv64-linux-gnu gcc-riscv64-linux-gnu gettext golang-1.24-go 1046s golang-1.24-src golang-go golang-src intltool-debian libarchive-zip-perl 1046s libasan8 libcc1-0 libdebhelper-perl libfile-stripnondeterminism-perl 1046s libgcc-14-dev libgomp1 libisl23 libitm1 liblsan0 libmpc3 libstdc++-14-dev 1046s libtool libtsan2 libubsan1 m4 po-debconf 1047s 0 upgraded, 47 newly installed, 0 to remove and 0 not upgraded. 1047s Need to get 139 MB of archives. 1047s After this operation, 641 MB of additional disk space will be used. 1047s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 m4 riscv64 1.4.19-7 [268 kB] 1048s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 autoconf all 2.72-3ubuntu1 [383 kB] 1048s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 autotools-dev all 20220109.1 [44.9 kB] 1048s Get:4 http://ftpmaster.internal/ubuntu plucky/main riscv64 automake all 1:1.17-3ubuntu1 [572 kB] 1048s Get:5 http://ftpmaster.internal/ubuntu plucky/main riscv64 autopoint all 0.23.1-1 [619 kB] 1048s Get:6 http://ftpmaster.internal/ubuntu plucky/main riscv64 libisl23 riscv64 0.27-1 [722 kB] 1048s Get:7 http://ftpmaster.internal/ubuntu plucky/main riscv64 libmpc3 riscv64 1.3.1-1build2 [61.5 kB] 1048s Get:8 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [15.2 MB] 1052s Get:9 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-14 riscv64 14.2.0-17ubuntu3 [1028 B] 1052s Get:10 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [5554 B] 1052s Get:11 http://ftpmaster.internal/ubuntu plucky/main riscv64 cpp riscv64 4:14.2.0-1ubuntu1 [22.4 kB] 1052s Get:12 http://ftpmaster.internal/ubuntu plucky/main riscv64 libcc1-0 riscv64 15-20250222-0ubuntu1 [46.0 kB] 1052s Get:13 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgomp1 riscv64 15-20250222-0ubuntu1 [148 kB] 1052s Get:14 http://ftpmaster.internal/ubuntu plucky/main riscv64 libitm1 riscv64 15-20250222-0ubuntu1 [29.7 kB] 1052s Get:15 http://ftpmaster.internal/ubuntu plucky/main riscv64 libasan8 riscv64 15-20250222-0ubuntu1 [3306 kB] 1052s Get:16 http://ftpmaster.internal/ubuntu plucky/main riscv64 liblsan0 riscv64 15-20250222-0ubuntu1 [1474 kB] 1053s Get:17 http://ftpmaster.internal/ubuntu plucky/main riscv64 libtsan2 riscv64 15-20250222-0ubuntu1 [2988 kB] 1053s Get:18 http://ftpmaster.internal/ubuntu plucky/main riscv64 libubsan1 riscv64 15-20250222-0ubuntu1 [1313 kB] 1054s Get:19 http://ftpmaster.internal/ubuntu plucky/main riscv64 libgcc-14-dev riscv64 14.2.0-17ubuntu3 [5982 kB] 1055s Get:20 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [29.9 MB] 1061s Get:21 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-14 riscv64 14.2.0-17ubuntu3 [526 kB] 1061s Get:22 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [1198 B] 1061s Get:23 http://ftpmaster.internal/ubuntu plucky/main riscv64 gcc riscv64 4:14.2.0-1ubuntu1 [4998 B] 1061s Get:24 http://ftpmaster.internal/ubuntu plucky/main riscv64 libstdc++-14-dev riscv64 14.2.0-17ubuntu3 [6135 kB] 1063s Get:25 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-14-riscv64-linux-gnu riscv64 14.2.0-17ubuntu3 [16.8 MB] 1067s Get:26 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-14 riscv64 14.2.0-17ubuntu3 [21.8 kB] 1067s Get:27 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++-riscv64-linux-gnu riscv64 4:14.2.0-1ubuntu1 [960 B] 1067s Get:28 http://ftpmaster.internal/ubuntu plucky/main riscv64 g++ riscv64 4:14.2.0-1ubuntu1 [1080 B] 1067s Get:29 http://ftpmaster.internal/ubuntu plucky/main riscv64 build-essential riscv64 12.10ubuntu1 [4934 B] 1067s Get:30 http://ftpmaster.internal/ubuntu plucky/main riscv64 libdebhelper-perl all 13.24.1ubuntu2 [95.4 kB] 1067s Get:31 http://ftpmaster.internal/ubuntu plucky/main riscv64 libtool all 2.5.4-4 [168 kB] 1067s Get:32 http://ftpmaster.internal/ubuntu plucky/main riscv64 dh-autoreconf all 20 [16.1 kB] 1067s Get:33 http://ftpmaster.internal/ubuntu plucky/main riscv64 libarchive-zip-perl all 1.68-1 [90.2 kB] 1067s Get:34 http://ftpmaster.internal/ubuntu plucky/main riscv64 libfile-stripnondeterminism-perl all 1.14.1-2 [20.3 kB] 1067s Get:35 http://ftpmaster.internal/ubuntu plucky/main riscv64 dh-strip-nondeterminism all 1.14.1-2 [5064 B] 1067s Get:36 http://ftpmaster.internal/ubuntu plucky/main riscv64 debugedit riscv64 1:5.1-2 [53.9 kB] 1067s Get:37 http://ftpmaster.internal/ubuntu plucky/main riscv64 dwz riscv64 0.15-1build6 [125 kB] 1067s Get:38 http://ftpmaster.internal/ubuntu plucky/main riscv64 gettext riscv64 0.23.1-1 [1201 kB] 1068s Get:39 http://ftpmaster.internal/ubuntu plucky/main riscv64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 1068s Get:40 http://ftpmaster.internal/ubuntu plucky/main riscv64 po-debconf all 1.0.21+nmu1 [233 kB] 1068s Get:41 http://ftpmaster.internal/ubuntu plucky/main riscv64 debhelper all 13.24.1ubuntu2 [895 kB] 1068s Get:42 http://ftpmaster.internal/ubuntu plucky/universe riscv64 dh-apport all 2.32.0-0ubuntu2 [19.3 kB] 1068s Get:43 http://ftpmaster.internal/ubuntu plucky/main riscv64 golang-1.24-src all 1.24.1-1 [22.0 MB] 1075s Get:44 http://ftpmaster.internal/ubuntu plucky/main riscv64 golang-1.24-go riscv64 1.24.1-1 [27.7 MB] 1082s Get:45 http://ftpmaster.internal/ubuntu plucky/main riscv64 golang-src all 2:1.24~2 [5136 B] 1082s Get:46 http://ftpmaster.internal/ubuntu plucky/main riscv64 golang-go riscv64 2:1.24~2 [44.0 kB] 1082s Get:47 http://ftpmaster.internal/ubuntu plucky/main riscv64 dh-golang all 1.63 [24.9 kB] 1093s Fetched 139 MB in 35s (3936 kB/s) 1094s Selecting previously unselected package m4. 1095s (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 ... 75584 files and directories currently installed.) 1095s Preparing to unpack .../00-m4_1.4.19-7_riscv64.deb ... 1095s Unpacking m4 (1.4.19-7) ... 1096s Selecting previously unselected package autoconf. 1096s Preparing to unpack .../01-autoconf_2.72-3ubuntu1_all.deb ... 1096s Unpacking autoconf (2.72-3ubuntu1) ... 1099s Selecting previously unselected package autotools-dev. 1099s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 1099s Unpacking autotools-dev (20220109.1) ... 1099s Selecting previously unselected package automake. 1099s Preparing to unpack .../03-automake_1%3a1.17-3ubuntu1_all.deb ... 1099s Unpacking automake (1:1.17-3ubuntu1) ... 1100s Selecting previously unselected package autopoint. 1100s Preparing to unpack .../04-autopoint_0.23.1-1_all.deb ... 1100s Unpacking autopoint (0.23.1-1) ... 1102s Selecting previously unselected package libisl23:riscv64. 1102s Preparing to unpack .../05-libisl23_0.27-1_riscv64.deb ... 1102s Unpacking libisl23:riscv64 (0.27-1) ... 1103s Selecting previously unselected package libmpc3:riscv64. 1103s Preparing to unpack .../06-libmpc3_1.3.1-1build2_riscv64.deb ... 1103s Unpacking libmpc3:riscv64 (1.3.1-1build2) ... 1104s Selecting previously unselected package cpp-14-riscv64-linux-gnu. 1104s Preparing to unpack .../07-cpp-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 1104s Unpacking cpp-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1118s Selecting previously unselected package cpp-14. 1118s Preparing to unpack .../08-cpp-14_14.2.0-17ubuntu3_riscv64.deb ... 1118s Unpacking cpp-14 (14.2.0-17ubuntu3) ... 1119s Selecting previously unselected package cpp-riscv64-linux-gnu. 1119s Preparing to unpack .../09-cpp-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1119s Unpacking cpp-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1119s Selecting previously unselected package cpp. 1119s Preparing to unpack .../10-cpp_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1120s Unpacking cpp (4:14.2.0-1ubuntu1) ... 1120s Selecting previously unselected package libcc1-0:riscv64. 1121s Preparing to unpack .../11-libcc1-0_15-20250222-0ubuntu1_riscv64.deb ... 1121s Unpacking libcc1-0:riscv64 (15-20250222-0ubuntu1) ... 1121s Selecting previously unselected package libgomp1:riscv64. 1121s Preparing to unpack .../12-libgomp1_15-20250222-0ubuntu1_riscv64.deb ... 1121s Unpacking libgomp1:riscv64 (15-20250222-0ubuntu1) ... 1122s Selecting previously unselected package libitm1:riscv64. 1122s Preparing to unpack .../13-libitm1_15-20250222-0ubuntu1_riscv64.deb ... 1122s Unpacking libitm1:riscv64 (15-20250222-0ubuntu1) ... 1123s Selecting previously unselected package libasan8:riscv64. 1123s Preparing to unpack .../14-libasan8_15-20250222-0ubuntu1_riscv64.deb ... 1123s Unpacking libasan8:riscv64 (15-20250222-0ubuntu1) ... 1126s Selecting previously unselected package liblsan0:riscv64. 1126s Preparing to unpack .../15-liblsan0_15-20250222-0ubuntu1_riscv64.deb ... 1126s Unpacking liblsan0:riscv64 (15-20250222-0ubuntu1) ... 1128s Selecting previously unselected package libtsan2:riscv64. 1129s Preparing to unpack .../16-libtsan2_15-20250222-0ubuntu1_riscv64.deb ... 1129s Unpacking libtsan2:riscv64 (15-20250222-0ubuntu1) ... 1132s Selecting previously unselected package libubsan1:riscv64. 1132s Preparing to unpack .../17-libubsan1_15-20250222-0ubuntu1_riscv64.deb ... 1132s Unpacking libubsan1:riscv64 (15-20250222-0ubuntu1) ... 1134s Selecting previously unselected package libgcc-14-dev:riscv64. 1134s Preparing to unpack .../18-libgcc-14-dev_14.2.0-17ubuntu3_riscv64.deb ... 1134s Unpacking libgcc-14-dev:riscv64 (14.2.0-17ubuntu3) ... 1144s Selecting previously unselected package gcc-14-riscv64-linux-gnu. 1145s Preparing to unpack .../19-gcc-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 1145s Unpacking gcc-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1169s Selecting previously unselected package gcc-14. 1169s Preparing to unpack .../20-gcc-14_14.2.0-17ubuntu3_riscv64.deb ... 1169s Unpacking gcc-14 (14.2.0-17ubuntu3) ... 1170s Selecting previously unselected package gcc-riscv64-linux-gnu. 1170s Preparing to unpack .../21-gcc-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1170s Unpacking gcc-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1171s Selecting previously unselected package gcc. 1171s Preparing to unpack .../22-gcc_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1171s Unpacking gcc (4:14.2.0-1ubuntu1) ... 1172s Selecting previously unselected package libstdc++-14-dev:riscv64. 1172s Preparing to unpack .../23-libstdc++-14-dev_14.2.0-17ubuntu3_riscv64.deb ... 1172s Unpacking libstdc++-14-dev:riscv64 (14.2.0-17ubuntu3) ... 1184s Selecting previously unselected package g++-14-riscv64-linux-gnu. 1184s Preparing to unpack .../24-g++-14-riscv64-linux-gnu_14.2.0-17ubuntu3_riscv64.deb ... 1184s Unpacking g++-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1197s Selecting previously unselected package g++-14. 1197s Preparing to unpack .../25-g++-14_14.2.0-17ubuntu3_riscv64.deb ... 1197s Unpacking g++-14 (14.2.0-17ubuntu3) ... 1198s Selecting previously unselected package g++-riscv64-linux-gnu. 1198s Preparing to unpack .../26-g++-riscv64-linux-gnu_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1198s Unpacking g++-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1198s Selecting previously unselected package g++. 1199s Preparing to unpack .../27-g++_4%3a14.2.0-1ubuntu1_riscv64.deb ... 1199s Unpacking g++ (4:14.2.0-1ubuntu1) ... 1199s Selecting previously unselected package build-essential. 1199s Preparing to unpack .../28-build-essential_12.10ubuntu1_riscv64.deb ... 1199s Unpacking build-essential (12.10ubuntu1) ... 1200s Selecting previously unselected package libdebhelper-perl. 1200s Preparing to unpack .../29-libdebhelper-perl_13.24.1ubuntu2_all.deb ... 1200s Unpacking libdebhelper-perl (13.24.1ubuntu2) ... 1201s Selecting previously unselected package libtool. 1201s Preparing to unpack .../30-libtool_2.5.4-4_all.deb ... 1201s Unpacking libtool (2.5.4-4) ... 1202s Selecting previously unselected package dh-autoreconf. 1202s Preparing to unpack .../31-dh-autoreconf_20_all.deb ... 1202s Unpacking dh-autoreconf (20) ... 1202s Selecting previously unselected package libarchive-zip-perl. 1203s Preparing to unpack .../32-libarchive-zip-perl_1.68-1_all.deb ... 1203s Unpacking libarchive-zip-perl (1.68-1) ... 1203s Selecting previously unselected package libfile-stripnondeterminism-perl. 1203s Preparing to unpack .../33-libfile-stripnondeterminism-perl_1.14.1-2_all.deb ... 1203s Unpacking libfile-stripnondeterminism-perl (1.14.1-2) ... 1204s Selecting previously unselected package dh-strip-nondeterminism. 1204s Preparing to unpack .../34-dh-strip-nondeterminism_1.14.1-2_all.deb ... 1204s Unpacking dh-strip-nondeterminism (1.14.1-2) ... 1205s Selecting previously unselected package debugedit. 1205s Preparing to unpack .../35-debugedit_1%3a5.1-2_riscv64.deb ... 1205s Unpacking debugedit (1:5.1-2) ... 1206s Selecting previously unselected package dwz. 1206s Preparing to unpack .../36-dwz_0.15-1build6_riscv64.deb ... 1206s Unpacking dwz (0.15-1build6) ... 1206s Selecting previously unselected package gettext. 1207s Preparing to unpack .../37-gettext_0.23.1-1_riscv64.deb ... 1207s Unpacking gettext (0.23.1-1) ... 1208s Selecting previously unselected package intltool-debian. 1208s Preparing to unpack .../38-intltool-debian_0.35.0+20060710.6_all.deb ... 1208s Unpacking intltool-debian (0.35.0+20060710.6) ... 1209s Selecting previously unselected package po-debconf. 1209s Preparing to unpack .../39-po-debconf_1.0.21+nmu1_all.deb ... 1209s Unpacking po-debconf (1.0.21+nmu1) ... 1210s Selecting previously unselected package debhelper. 1210s Preparing to unpack .../40-debhelper_13.24.1ubuntu2_all.deb ... 1210s Unpacking debhelper (13.24.1ubuntu2) ... 1212s Selecting previously unselected package dh-apport. 1212s Preparing to unpack .../41-dh-apport_2.32.0-0ubuntu2_all.deb ... 1212s Unpacking dh-apport (2.32.0-0ubuntu2) ... 1213s Selecting previously unselected package golang-1.24-src. 1213s Preparing to unpack .../42-golang-1.24-src_1.24.1-1_all.deb ... 1213s Unpacking golang-1.24-src (1.24.1-1) ... 1266s Selecting previously unselected package golang-1.24-go. 1266s Preparing to unpack .../43-golang-1.24-go_1.24.1-1_riscv64.deb ... 1266s Unpacking golang-1.24-go (1.24.1-1) ... 1290s Selecting previously unselected package golang-src. 1290s Preparing to unpack .../44-golang-src_2%3a1.24~2_all.deb ... 1290s Unpacking golang-src (2:1.24~2) ... 1290s Selecting previously unselected package golang-go:riscv64. 1291s Preparing to unpack .../45-golang-go_2%3a1.24~2_riscv64.deb ... 1291s Unpacking golang-go:riscv64 (2:1.24~2) ... 1291s Selecting previously unselected package dh-golang. 1292s Preparing to unpack .../46-dh-golang_1.63_all.deb ... 1292s Unpacking dh-golang (1.63) ... 1295s Setting up golang-1.24-src (1.24.1-1) ... 1295s Setting up libarchive-zip-perl (1.68-1) ... 1295s Setting up libdebhelper-perl (13.24.1ubuntu2) ... 1295s Setting up m4 (1.4.19-7) ... 1295s Setting up libgomp1:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up dh-apport (2.32.0-0ubuntu2) ... 1295s Setting up autotools-dev (20220109.1) ... 1295s Setting up libmpc3:riscv64 (1.3.1-1build2) ... 1295s Setting up autopoint (0.23.1-1) ... 1295s Setting up golang-1.24-go (1.24.1-1) ... 1295s Setting up autoconf (2.72-3ubuntu1) ... 1295s Setting up libubsan1:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up dwz (0.15-1build6) ... 1295s Setting up libasan8:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up debugedit (1:5.1-2) ... 1295s Setting up libtsan2:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up libisl23:riscv64 (0.27-1) ... 1295s Setting up golang-src (2:1.24~2) ... 1295s Setting up libcc1-0:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up liblsan0:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up libitm1:riscv64 (15-20250222-0ubuntu1) ... 1295s Setting up automake (1:1.17-3ubuntu1) ... 1295s update-alternatives: using /usr/bin/automake-1.17 to provide /usr/bin/automake (automake) in auto mode 1295s Setting up libfile-stripnondeterminism-perl (1.14.1-2) ... 1295s Setting up gettext (0.23.1-1) ... 1295s Setting up golang-go:riscv64 (2:1.24~2) ... 1295s Setting up cpp-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1295s Setting up intltool-debian (0.35.0+20060710.6) ... 1295s Setting up cpp-14 (14.2.0-17ubuntu3) ... 1295s Setting up dh-strip-nondeterminism (1.14.1-2) ... 1295s Setting up libgcc-14-dev:riscv64 (14.2.0-17ubuntu3) ... 1295s Setting up cpp-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1295s Setting up libstdc++-14-dev:riscv64 (14.2.0-17ubuntu3) ... 1295s Setting up po-debconf (1.0.21+nmu1) ... 1295s Setting up gcc-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1295s Setting up cpp (4:14.2.0-1ubuntu1) ... 1295s Setting up g++-14-riscv64-linux-gnu (14.2.0-17ubuntu3) ... 1295s Setting up gcc-14 (14.2.0-17ubuntu3) ... 1295s Setting up gcc-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1295s Setting up g++-riscv64-linux-gnu (4:14.2.0-1ubuntu1) ... 1295s Setting up g++-14 (14.2.0-17ubuntu3) ... 1295s Setting up libtool (2.5.4-4) ... 1295s Setting up gcc (4:14.2.0-1ubuntu1) ... 1295s Setting up dh-autoreconf (20) ... 1295s Setting up g++ (4:14.2.0-1ubuntu1) ... 1295s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 1295s Setting up build-essential (12.10ubuntu1) ... 1295s Setting up debhelper (13.24.1ubuntu2) ... 1295s Setting up dh-golang (1.63) ... 1295s Processing triggers for man-db (2.13.0-1) ... 1303s Processing triggers for install-info (7.1.1-1) ... 1308s Processing triggers for libc-bin (2.41-1ubuntu2) ... 1322s autopkgtest [22:24:27]: test command1: ./debian/tests/test 1322s autopkgtest [22:24:27]: test command1: [----------------------- 2252s === RUN TestRunSignal 2252s === RUN TestRunSignal/Send_SIGINT_exits 2252s INFO Starting WSL Pro Service version Dev 2252s === RUN TestRunSignal/Send_SIGTERM_exits 2252s INFO Starting WSL Pro Service version Dev 2252s --- PASS: TestRunSignal (0.24s) 2252s --- PASS: TestRunSignal/Send_SIGINT_exits (0.11s) 2252s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.11s) 2252s === RUN TestRun 2252s === PAUSE TestRun 2252s === CONT TestRun 2252s === RUN TestRun/Run_and_usage_error_only_does_not_fail 2252s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 2252s === RUN TestRun/Run_and_exit_successfully 2252s === PAUSE TestRun/Run_and_exit_successfully 2252s === RUN TestRun/Run_and_return_error 2252s === PAUSE TestRun/Run_and_return_error 2252s === RUN TestRun/Run_and_return_usage_error 2252s === PAUSE TestRun/Run_and_return_usage_error 2252s === CONT TestRun/Run_and_usage_error_only_does_not_fail 2252s === CONT TestRun/Run_and_return_error 2252s INFO Starting WSL Pro Service version Dev 2252s INFO Starting WSL Pro Service version Dev 2252s === CONT TestRun/Run_and_return_usage_error 2252s ERROR context.Background Error requested 2252s === CONT TestRun/Run_and_exit_successfully 2252s INFO Starting WSL Pro Service version Dev 2252s INFO Starting WSL Pro Service version Dev 2252s ERROR context.Background Error requested 2252s --- PASS: TestRun (0.02s) 2252s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 2252s --- PASS: TestRun/Run_and_return_error (0.11s) 2252s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 2252s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 2252s PASS 2252s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 1.403s 2675s === RUN TestHelp 2675s time="2025-03-15T22:40:26Z" level=debug msg="No Landscape configuration detected: nothing to do" 2675s --- PASS: TestHelp (0.26s) 2675s === RUN TestCompletion 2675s time="2025-03-15T22:40:26Z" level=debug msg="No Landscape configuration detected: nothing to do" 2675s --- PASS: TestCompletion (0.09s) 2675s === RUN TestVersion 2675s --- PASS: TestVersion (0.05s) 2675s === RUN TestConfigBadArg 2675s --- PASS: TestConfigBadArg (0.05s) 2675s === RUN TestConfigArg 2675s --- PASS: TestConfigArg (0.06s) 2675s === RUN TestConfigAutoDetect 2675s time="2025-03-15T22:40:26Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 2675s --- PASS: TestConfigAutoDetect (0.10s) 2675s === RUN TestNoUsageError 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 2675s --- PASS: TestNoUsageError (0.06s) 2675s === RUN TestUsageError 2675s === PAUSE TestUsageError 2675s === RUN TestCanQuitWhenExecute 2675s === PAUSE TestCanQuitWhenExecute 2675s === RUN TestCanQuitTwice 2675s === PAUSE TestCanQuitTwice 2675s === RUN TestAppCanQuitWithoutExecute 2675s === PAUSE TestAppCanQuitWithoutExecute 2675s === RUN TestAppRunFailsOnComponentsCreationAndQuit 2675s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 2675s === RUN TestAppGetRootCmd 2675s === PAUSE TestAppGetRootCmd 2675s === RUN TestWithProMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithProMock (0.00s) 2675s === RUN TestWithWslPathMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithWslPathMock (0.00s) 2675s === RUN TestWithWslInfoMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithWslInfoMock (0.00s) 2675s === RUN TestWithCmdExeMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithCmdExeMock (0.00s) 2675s === CONT TestAppCanQuitWithoutExecute 2675s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 2675s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 2675s === CONT TestAppGetRootCmd 2675s === CONT TestUsageError 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s --- PASS: TestAppGetRootCmd (0.08s) 2675s === CONT TestAppRunFailsOnComponentsCreationAndQuit 2675s === CONT TestCanQuitTwice 2675s --- PASS: TestUsageError (0.09s) 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41703" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:74 New() Building new daemon" 2675s time="2025-03-15T22:40:27Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build1717580392/b377/wsl-pro-service.yaml" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:40:27Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:74 New() Building new daemon" 2675s time="2025-03-15T22:42:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 2675s time="2025-03-15T22:42:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:42:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:42:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 2675s time="2025-03-15T22:44:19Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:44:19Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:44:19Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s === CONT TestCanQuitWhenExecute 2675s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (232.72s) 2675s time="2025-03-15T22:44:19Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:44:20Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:33627" 2675s time="2025-03-15T22:44:20Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33077" 2675s time="2025-03-15T22:44:20Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 2675s time="2025-03-15T22:44:20Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build1717580392/b377/wsl-pro-service.yaml" 2675s time="2025-03-15T22:44:20Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 2675s time="2025-03-15T22:44:20Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:43 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 2675s time="2025-03-15T22:44:20Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:74 New() Building new daemon" 2675s time="2025-03-15T22:44:21Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 2675s time="2025-03-15T22:44:21Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 2675s time="2025-03-15T22:45:37Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 2675s time="2025-03-15T22:45:37Z" level=warning msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:185 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 2675s time="2025-03-15T22:45:37Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:45:37Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.rpOsn9/build.xVp/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 2675s time="2025-03-15T22:45:37Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.rpOsn9/build.xVp/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:45:37Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s time="2025-03-15T22:45:37Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:45:37Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:45:37Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s --- PASS: TestCanQuitTwice (310.89s) 2675s time="2025-03-15T22:46:26Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:152 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 2675s time="2025-03-15T22:46:27Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:46:27Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:46:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:290 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:33077" 2675s time="2025-03-15T22:46:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:172 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 2675s time="2025-03-15T22:46:59Z" level=warning msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:183 (*Daemon).Serve.func1() Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:33077: connect: connection refused\"" 2675s time="2025-03-15T22:46:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:208 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 2675s time="2025-03-15T22:46:59Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s time="2025-03-15T22:46:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:219 (*Daemon).Quit() Stopping daemon requested." 2675s time="2025-03-15T22:46:59Z" level=info msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:225 (*Daemon).Quit() Waiting for active requests to close." 2675s time="2025-03-15T22:46:59Z" level=debug msg="/tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:233 (*Daemon).Quit() All connections have now ended." 2675s --- PASS: TestCanQuitWhenExecute (159.66s) 2675s PASS 2675s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 393.727s 2675s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 2675s === RUN TestApplyProToken 2675s === PAUSE TestApplyProToken 2675s === RUN TestApplyLandscapeConfig 2675s === PAUSE TestApplyLandscapeConfig 2675s === RUN TestWithProMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithProMock (0.01s) 2675s === RUN TestWithLandscapeConfigMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithLandscapeConfigMock (0.00s) 2675s === RUN TestWithWslPathMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithWslPathMock (0.00s) 2675s === RUN TestWithWslInfoMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithWslInfoMock (0.00s) 2675s === RUN TestWithCmdExeMock 2675s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 2675s --- SKIP: TestWithCmdExeMock (0.01s) 2675s === CONT TestApplyProToken 2675s === RUN TestApplyProToken/Error_calling_pro_attach 2675s === PAUSE TestApplyProToken/Error_calling_pro_attach 2675s === RUN TestApplyProToken/Success_attaching 2675s === PAUSE TestApplyProToken/Success_attaching 2675s === CONT TestApplyLandscapeConfig 2675s === RUN TestApplyProToken/Success_detaching 2675s === PAUSE TestApplyProToken/Success_detaching 2675s === RUN TestApplyProToken/Error_calling_pro_detach 2675s === PAUSE TestApplyProToken/Error_calling_pro_detach 2675s === CONT TestApplyProToken/Error_calling_pro_attach 2675s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 2675s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 2675s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 2675s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 2675s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 2675s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 2675s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 2675s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 2675s === CONT TestApplyProToken/Error_calling_pro_detach 2675s time="2025-03-15T22:41:09Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 2675s time="2025-03-15T22:41:09Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 2675s === CONT TestApplyProToken/Success_detaching 2675s time="2025-03-15T22:42:15Z" level=info msg="ApplyProToken: Received empty token: detaching" 2675s === CONT TestApplyProToken/Success_attaching 2675s time="2025-03-15T22:43:20Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 2675s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 2675s time="2025-03-15T22:43:21Z" level=info msg="ApplyLandscapeConfig: received config: registering" 2675s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 2675s time="2025-03-15T22:44:25Z" level=info msg="ApplyLandscapeConfig: received config: registering" 2675s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 2675s --- PASS: TestApplyProToken (0.02s) 2675s --- PASS: TestApplyProToken/Error_calling_pro_detach (65.70s) 2675s --- PASS: TestApplyProToken/Success_detaching (65.80s) 2675s --- PASS: TestApplyProToken/Error_calling_pro_attach (132.43s) 2675s --- PASS: TestApplyProToken/Success_attaching (128.32s) 2675s time="2025-03-15T22:45:29Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 2675s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 2675s time="2025-03-15T22:45:29Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 2675s --- PASS: TestApplyLandscapeConfig (0.03s) 2675s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (63.46s) 2675s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (64.75s) 2675s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (52.46s) 2675s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (51.74s) 2675s PASS 2675s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 313.682s 2675s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 3276s === RUN TestNew 3276s === PAUSE TestNew 3276s === RUN TestServe 3276s === PAUSE TestServe 3276s === RUN TestServeAndQuit 3276s === PAUSE TestServeAndQuit 3276s === RUN TestReconnection 3276s === PAUSE TestReconnection 3276s === RUN TestWithProMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithProMock (0.01s) 3276s === RUN TestWithWslPathMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithWslPathMock (0.00s) 3276s === RUN TestWithWslInfoMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithWslInfoMock (0.00s) 3276s === RUN TestWithCmdExeMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithCmdExeMock (0.00s) 3276s === CONT TestNew 3276s === RUN TestNew/Success 3276s === PAUSE TestNew/Success 3276s === CONT TestReconnection 3276s === RUN TestReconnection/Success_connecting_after_failing_to_connect 3276s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 3276s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 3276s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 3276s === RUN TestNew/Error_when_WslPath_returns_error 3276s === PAUSE TestNew/Error_when_WslPath_returns_error 3276s === CONT TestServe 3276s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 3276s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 3276s === CONT TestServeAndQuit 3276s === RUN TestServeAndQuit/Success_with_graceful_quit 3276s === PAUSE TestServeAndQuit/Success_with_graceful_quit 3276s === RUN TestServeAndQuit/Success_with_forceful_quit 3276s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 3276s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 3276s === RUN TestServe/No_connection_because_there_is_no_server 3276s === PAUSE TestServe/No_connection_because_there_is_no_server 3276s === RUN TestServe/No_connection_because_there_are_no_certificates 3276s === PAUSE TestServe/No_connection_because_there_are_no_certificates 3276s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 3276s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 3276s === RUN TestServe/Success 3276s === PAUSE TestServe/Success 3276s === RUN TestServe/No_connection_because_the_port_file_is_empty 3276s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 3276s === RUN TestServe/No_connection_because_the_port_file_has_port_0 3276s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 3276s === RUN TestServe/Error_because_the_context_is_pre-cancelled 3276s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 3276s === PAUSE TestServeAndQuit/Success_with_forceful_quit 3276s === RUN TestServeAndQuit/Success_with_double_quit 3276s === PAUSE TestServeAndQuit/Success_with_double_quit 3276s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 3276s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 3276s === CONT TestNew/Success 3276s === RUN TestServe/Error_because_the_notifier_returns_an_error 3276s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 3276s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 3276s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 3276s === RUN TestServe/Success_with_systemd_notifier_returning_true 3276s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 3276s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 3276s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 3276s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 3276s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 3276s === CONT TestReconnection/Success_connecting_after_failing_to_connect 3276s time="2025-03-15T22:47:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:47:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:47:00Z" level=debug msg="Building new daemon" 3276s time="2025-03-15T22:47:00Z" level=debug msg="Building new daemon" 3276s time="2025-03-15T22:49:13Z" level=debug msg="Ready state sent to systemd" 3276s time="2025-03-15T22:49:13Z" level=info msg="Daemon: connecting to Windows Agent" 3276s time="2025-03-15T22:49:13Z" level=debug msg="Updated systemd status to \"Connecting\"" 3276s time="2025-03-15T22:49:13Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_failing_to_connect597429645/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect597429645/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 3276s time="2025-03-15T22:49:13Z" level=info msg="Reconnecting to Windows host in 1 seconds" 3276s time="2025-03-15T22:49:13Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 3276s time="2025-03-15T22:49:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35985" 3276s time="2025-03-15T22:49:15Z" level=info msg="Daemon: connecting to Windows Agent" 3276s time="2025-03-15T22:49:15Z" level=debug msg="Updated systemd status to \"Connecting\"" 3276s === CONT TestServeAndQuit/Success_with_graceful_quit 3276s time="2025-03-15T22:49:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:49:15Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33211" 3276s time="2025-03-15T22:49:15Z" level=debug msg="Building new daemon" 3276s === NAME TestReconnection/Success_connecting_after_failing_to_connect 3276s daemon_test.go:391: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:391 3276s Error: Condition never satisfied 3276s Test: TestReconnection/Success_connecting_after_failing_to_connect 3276s Messages: Daemon never connected to agent's service 3276s time="2025-03-15T22:49:34Z" level=info msg="Stopping daemon requested." 3276s time="2025-03-15T22:49:34Z" level=info msg="Stopping active requests." 3276s time="2025-03-15T22:50:15Z" level=debug msg="Updated systemd status to \"Stopped\"" 3276s time="2025-03-15T22:50:15Z" level=debug msg="All connections have now ended." 3276s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 3276s time="2025-03-15T22:50:16Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:50:16Z" level=debug msg="Building new daemon" 3276s time="2025-03-15T22:51:27Z" level=debug msg="Ready state sent to systemd" 3276s time="2025-03-15T22:51:27Z" level=info msg="Daemon: connecting to Windows Agent" 3276s time="2025-03-15T22:51:27Z" level=debug msg="Updated systemd status to \"Connecting\"" 3276s === NAME TestServeAndQuit/Success_with_graceful_quit 3276s daemon_test.go:289: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:289 3276s Error: Condition never satisfied 3276s Test: TestServeAndQuit/Success_with_graceful_quit 3276s Messages: Systemd state should have been set to "STATUS=Connected" 3276s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 3276s time="2025-03-15T22:51:47Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:51:47Z" level=debug msg="Building new daemon" 3276s time="2025-03-15T22:53:04Z" level=debug msg="Updated systemd status to \"Stopped\"" 3276s time="2025-03-15T22:53:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36871" 3276s time="2025-03-15T22:53:06Z" level=debug msg="Ready state sent to systemd" 3276s time="2025-03-15T22:53:06Z" level=info msg="Daemon: connecting to Windows Agent" 3276s time="2025-03-15T22:53:06Z" level=debug msg="Updated systemd status to \"Connecting\"" 3276s === NAME TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 3276s daemon_test.go:370: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:370 3276s Error: Condition never satisfied 3276s Test: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 3276s Messages: Service should have set systemd state to Connected 3276s time="2025-03-15T22:54:06Z" level=info msg="Stopping daemon requested." 3276s time="2025-03-15T22:54:06Z" level=info msg="Stopping active requests." 3276s time="2025-03-15T22:54:29Z" level=debug msg="Updated systemd status to \"Stopped\"" 3276s time="2025-03-15T22:54:29Z" level=debug msg="All connections have now ended." 3276s === CONT TestNew/Error_when_WslPath_returns_error 3276s time="2025-03-15T22:54:29Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:54:29Z" level=debug msg="Building new daemon" 3276s time="2025-03-15T22:54:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45557" 3276s time="2025-03-15T22:54:44Z" level=debug msg="Ready state sent to systemd" 3276s time="2025-03-15T22:54:44Z" level=info msg="Daemon: connecting to Windows Agent" 3276s time="2025-03-15T22:54:44Z" level=debug msg="Updated systemd status to \"Connecting\"" 3276s === NAME TestReconnection/Success_connecting_after_previous_connection_dropped 3276s daemon_test.go:370: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:370 3276s Error: Condition never satisfied 3276s Test: TestReconnection/Success_connecting_after_previous_connection_dropped 3276s Messages: Service should have set systemd state to Connected 3276s time="2025-03-15T22:55:44Z" level=info msg="Stopping daemon requested." 3276s time="2025-03-15T22:55:44Z" level=info msg="Stopping active requests." 3276s time="2025-03-15T22:56:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 3276s time="2025-03-15T22:56:00Z" level=debug msg="All connections have now ended." 3276s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 3276s --- FAIL: TestReconnection (0.02s) 3276s --- FAIL: TestReconnection/Success_connecting_after_failing_to_connect (195.01s) 3276s --- FAIL: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (253.26s) 3276s --- FAIL: TestReconnection/Success_connecting_after_previous_connection_dropped (253.55s) 3276s time="2025-03-15T22:56:01Z" level=debug msg="No Landscape configuration detected: nothing to do" 3276s time="2025-03-15T22:56:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37853" 3276s time="2025-03-15T22:56:01Z" level=debug msg="Building new daemon" 3276s panic: test timed out after 10m0s 3276s running tests: 3276s TestNew/Error_when_WslPath_returns_error (2m32s) 3276s TestServe/No_connection_because_the_port_file_has_a_negative_port (1m0s) 3276s 3276s goroutine 290 [running]: 3276s testing.(*M).startAlarm.func1() 3276s /usr/lib/go-1.24/src/testing/testing.go:2484 +0x304 3276s created by time.goFunc 3276s /usr/lib/go-1.24/src/time/sleep.go:215 +0x58 3276s 3276s goroutine 1 [chan receive]: 3276s testing.tRunner.func1() 3276s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3276s testing.tRunner(0xc0000ac380, 0xc0001b1c88) 3276s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3276s testing.runTests(0xc0000dc2a0, {0xc39200, 0x8, 0x8}, {0x1d704?, 0xc0001b66c0?, 0xc473a0?}) 3276s /usr/lib/go-1.24/src/testing/testing.go:2277 +0x440 3276s testing.(*M).Run(0xc00010b900) 3276s /usr/lib/go-1.24/src/testing/testing.go:2142 +0x5bc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestMain(...) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:24 3276s main.main() 3276s _testmain.go:63 +0xbc 3276s 3276s goroutine 18 [chan receive]: 3276s testing.tRunner.func1() 3276s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3276s testing.tRunner(0xc0000ac540, 0x70fa48) 3276s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3276s created by testing.(*T).Run in goroutine 1 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 19 [chan receive]: 3276s testing.tRunner.func1() 3276s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3276s testing.tRunner(0xc0000ac8c0, 0x70fa58) 3276s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3276s created by testing.(*T).Run in goroutine 1 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 7 [chan receive]: 3276s testing.tRunner.func1() 3276s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3276s testing.tRunner(0xc000161180, 0x70fa60) 3276s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3276s created by testing.(*T).Run in goroutine 1 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 270 [IO wait]: 3276s internal/poll.runtime_pollWait(0x7ffb68e6fb68, 0x72) 3276s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3276s internal/poll.(*pollDesc).wait(0xc000177b00?, 0x2f942c?, 0x0) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3276s internal/poll.(*pollDesc).waitRead(...) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3276s internal/poll.(*FD).Accept(0xc000177b00) 3276s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:620 +0x258 3276s net.(*netFD).accept(0xc000177b00) 3276s /usr/lib/go-1.24/src/net/fd_unix.go:172 +0x34 3276s net.(*TCPListener).accept(0xc0002a3980) 3276s /usr/lib/go-1.24/src/net/tcpsock_posix.go:159 +0x30 3276s net.(*TCPListener).Accept(0xc0002a3980) 3276s /usr/lib/go-1.24/src/net/tcpsock.go:380 +0x38 3276s google.golang.org/grpc.(*Server).Serve(0xc000173400, {0x7cbc38, 0xc0002a3980}) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/vendor/google.golang.org/grpc/server.go:884 +0x3d0 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent.func1() 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:81 +0xfc 3276s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent in goroutine 13 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/testutils/mock_agent.go:75 +0x47c 3276s 3276s goroutine 12 [syscall]: 3276s syscall.Syscall6(0x5f, 0x3, 0xf, 0xc000273a78, 0x4, 0xc0000db4d0, 0x0) 3276s /usr/lib/go-1.24/src/syscall/syscall_linux.go:95 +0x2c 3276s internal/syscall/unix.Waitid(0xc000273aa6?, 0x17b94c?, 0xc00032cad0?, 0x0?, 0xc00035cc60?) 3276s /usr/lib/go-1.24/src/internal/syscall/unix/waitid_linux.go:18 +0x70 3276s os.(*Process).pidfdWait.func1(...) 3276s /usr/lib/go-1.24/src/os/pidfd_linux.go:106 3276s os.ignoringEINTR(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:251 3276s os.(*Process).pidfdWait(0xc00031d180) 3276s /usr/lib/go-1.24/src/os/pidfd_linux.go:105 +0x1b8 3276s os.(*Process).wait(0x7ffb68ff95c0?) 3276s /usr/lib/go-1.24/src/os/exec_unix.go:27 +0x38 3276s os.(*Process).Wait(...) 3276s /usr/lib/go-1.24/src/os/exec.go:358 3276s os/exec.(*Cmd).Wait(0xc000212c00) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:922 +0x44 3276s os/exec.(*Cmd).Run(0xc000212c00) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:626 +0x44 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc000212c00) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:231 +0x140 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).UserProfileDir(0xc000335110, {0x7ccfc8, 0xc0002ea0f0}) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:203 +0x1a8 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon.New({0x7ccfc8, 0xc0002ea0f0}, 0xc000335110, {0x0, 0x0, 0xf?}) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:86 +0xf4 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestNew.func1(0xc0001c4380) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:52 +0x194 3276s testing.tRunner(0xc0001c4380, 0xc00000edd0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 18 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 278 [IO wait]: 3276s internal/poll.runtime_pollWait(0x7ffb68e6f820, 0x72) 3276s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3276s internal/poll.(*pollDesc).wait(0xc000329b60?, 0xc0002fe400?, 0x1) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3276s internal/poll.(*pollDesc).waitRead(...) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3276s internal/poll.(*FD).Read(0xc000329b60, {0xc0002fe400, 0x200, 0x200}) 3276s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3276s os.(*File).read(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:29 3276s os.(*File).Read(0xc00008e6e8, {0xc0002fe400?, 0xc503c?, 0x611300?}) 3276s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3276s bytes.(*Buffer).ReadFrom(0xc00035cd50, {0x7c66f8, 0xc000052810}) 3276s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3276s io.copyBuffer({0x7c6c80, 0xc00035cd50}, {0x7c66f8, 0xc000052810}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os.genericWriteTo(0xc00008e6e8?, {0x7c6c80, 0xc00035cd50}) 3276s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3276s os.(*File).WriteTo(0xc00008e6e8, {0x7c6c80, 0xc00035cd50}) 3276s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3276s io.copyBuffer({0x7c6c80, 0xc00035cd50}, {0x7c6778, 0xc00008e6e8}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os/exec.(*Cmd).writerDescriptor.func1() 3276s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3276s os/exec.(*Cmd).Start.func2(0xc0000224f8?) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3276s created by os/exec.(*Cmd).Start in goroutine 12 3276s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3276s 3276s goroutine 107 [chan send]: 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1.1() 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:278 +0x50 3276s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1 in goroutine 25 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:277 +0x244 3276s 3276s goroutine 271 [IO wait]: 3276s internal/poll.runtime_pollWait(0x7ffb68e6fc80, 0x72) 3276s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3276s internal/poll.(*pollDesc).wait(0xc000392900?, 0xc000340800?, 0x1) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3276s internal/poll.(*pollDesc).waitRead(...) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3276s internal/poll.(*FD).Read(0xc000392900, {0xc000340800, 0x200, 0x200}) 3276s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3276s os.(*File).read(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:29 3276s os.(*File).Read(0xc000052898, {0xc000340800?, 0xc503c?, 0x611300?}) 3276s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3276s bytes.(*Buffer).ReadFrom(0xc000386d80, {0x7c66f8, 0xc00008e7e0}) 3276s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3276s io.copyBuffer({0x7c6c80, 0xc000386d80}, {0x7c66f8, 0xc00008e7e0}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os.genericWriteTo(0xc000052898?, {0x7c6c80, 0xc000386d80}) 3276s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3276s os.(*File).WriteTo(0xc000052898, {0x7c6c80, 0xc000386d80}) 3276s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3276s io.copyBuffer({0x7c6c80, 0xc000386d80}, {0x7c6778, 0xc000052898}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os/exec.(*Cmd).writerDescriptor.func1() 3276s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3276s os/exec.(*Cmd).Start.func2(0x0?) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3276s created by os/exec.(*Cmd).Start in goroutine 13 3276s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3276s 3276s goroutine 26 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0000fc000) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1(0xc0000fc000) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:255 +0x58 3276s testing.tRunner(0xc0000fc000, 0xc000094d00) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 7 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 13 [syscall]: 3276s syscall.Syscall6(0x5f, 0x3, 0x11, 0xc0000358b8, 0x4, 0xc0002fa750, 0x0) 3276s /usr/lib/go-1.24/src/syscall/syscall_linux.go:95 +0x2c 3276s internal/syscall/unix.Waitid(0xc0000358e6?, 0x17b94c?, 0xc0002e0a40?, 0x0?, 0xc000386c90?) 3276s /usr/lib/go-1.24/src/internal/syscall/unix/waitid_linux.go:18 +0x70 3276s os.(*Process).pidfdWait.func1(...) 3276s /usr/lib/go-1.24/src/os/pidfd_linux.go:106 3276s os.ignoringEINTR(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:251 3276s os.(*Process).pidfdWait(0xc0002c9b80) 3276s /usr/lib/go-1.24/src/os/pidfd_linux.go:105 +0x1b8 3276s os.(*Process).wait(0x7ffb68ff9108?) 3276s /usr/lib/go-1.24/src/os/exec_unix.go:27 +0x38 3276s os.(*Process).Wait(...) 3276s /usr/lib/go-1.24/src/os/exec.go:358 3276s os/exec.(*Cmd).Wait(0xc0001a6c00) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:922 +0x44 3276s os/exec.(*Cmd).Run(0xc0001a6c00) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:626 +0x44 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc0001a6c00) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:231 +0x140 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).UserProfileDir(0xc000386120, {0x7ccfc8, 0xc000350320}) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:190 +0x100 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon.New({0x7ccfc8, 0xc000350320}, 0xc000386120, {0xc000035ed0, 0x1, 0x1?}) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon.go:86 +0xf4 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c4540) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:164 +0x750 3276s testing.tRunner(0xc0001c4540, 0xc0000223c0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 14 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c48c0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c48c0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c48c0, 0xc0000223d8) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 15 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c4c40) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c4c40) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c4c40, 0xc0000223f0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 16 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c4fc0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c4fc0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c4fc0, 0xc000022408) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 34 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c5340) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c5340) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c5340, 0xc000022420) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 35 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c56c0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c56c0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c56c0, 0xc000022450) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 36 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c5a40) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c5a40) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c5a40, 0xc000022468) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 37 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001c5dc0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001c5dc0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001c5dc0, 0xc000022480) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 27 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0000fc380) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1(0xc0000fc380) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:255 +0x58 3276s testing.tRunner(0xc0000fc380, 0xc000094d30) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 7 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 28 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0000fc700) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1(0xc0000fc700) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:255 +0x58 3276s testing.tRunner(0xc0000fc700, 0xc000094d60) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 7 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 38 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001ce1c0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001ce1c0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001ce1c0, 0xc000022498) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 39 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001ce540) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001ce540) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001ce540, 0xc0000224b0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 40 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001ce8c0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001ce8c0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001ce8c0, 0xc0000224c8) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 41 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001cec40) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001cec40) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001cec40, 0xc0000224e0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 42 [chan receive]: 3276s testing.(*testState).waitParallel(0xc00009c320) 3276s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3276s testing.(*T).Parallel(0xc0001cefc0) 3276s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3276s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc0001cefc0) 3276s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/daemon/daemon_test.go:113 +0x40 3276s testing.tRunner(0xc0001cefc0, 0xc0000224f8) 3276s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3276s created by testing.(*T).Run in goroutine 19 3276s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3276s 3276s goroutine 273 [select]: 3276s os/exec.(*Cmd).watchCtx(0xc0001a6c00, 0xc0002d9650) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:789 +0x94 3276s created by os/exec.(*Cmd).Start in goroutine 13 3276s /usr/lib/go-1.24/src/os/exec/exec.go:775 +0x740 3276s 3276s goroutine 272 [IO wait]: 3276s internal/poll.runtime_pollWait(0x7ffb68e6f708, 0x72) 3276s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3276s internal/poll.(*pollDesc).wait(0xc0003929c0?, 0xc000340a00?, 0x1) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3276s internal/poll.(*pollDesc).waitRead(...) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3276s internal/poll.(*FD).Read(0xc0003929c0, {0xc000340a00, 0x200, 0x200}) 3276s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3276s os.(*File).read(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:29 3276s os.(*File).Read(0xc0000528b0, {0xc000340a00?, 0xc503c?, 0x611300?}) 3276s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3276s bytes.(*Buffer).ReadFrom(0xc000386db0, {0x7c66f8, 0xc00008e7e8}) 3276s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3276s io.copyBuffer({0x7c6c80, 0xc000386db0}, {0x7c66f8, 0xc00008e7e8}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os.genericWriteTo(0xc0000528b0?, {0x7c6c80, 0xc000386db0}) 3276s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3276s os.(*File).WriteTo(0xc0000528b0, {0x7c6c80, 0xc000386db0}) 3276s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3276s io.copyBuffer({0x7c6c80, 0xc000386db0}, {0x7c6778, 0xc0000528b0}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os/exec.(*Cmd).writerDescriptor.func1() 3276s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3276s os/exec.(*Cmd).Start.func2(0xc0002d8bd0?) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3276s created by os/exec.(*Cmd).Start in goroutine 13 3276s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3276s 3276s goroutine 279 [IO wait]: 3276s internal/poll.runtime_pollWait(0x7ffb68e6fa50, 0x72) 3276s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3276s internal/poll.(*pollDesc).wait(0xc000329c20?, 0xc0002fe600?, 0x1) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3276s internal/poll.(*pollDesc).waitRead(...) 3276s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3276s internal/poll.(*FD).Read(0xc000329c20, {0xc0002fe600, 0x200, 0x200}) 3276s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3276s os.(*File).read(...) 3276s /usr/lib/go-1.24/src/os/file_posix.go:29 3276s os.(*File).Read(0xc00008e700, {0xc0002fe600?, 0xc503c?, 0x611300?}) 3276s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3276s bytes.(*Buffer).ReadFrom(0xc00035cd80, {0x7c66f8, 0xc000052818}) 3276s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3276s io.copyBuffer({0x7c6c80, 0xc00035cd80}, {0x7c66f8, 0xc000052818}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os.genericWriteTo(0xc00008e700?, {0x7c6c80, 0xc00035cd80}) 3276s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3276s os.(*File).WriteTo(0xc00008e700, {0x7c6c80, 0xc00035cd80}) 3276s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3276s io.copyBuffer({0x7c6c80, 0xc00035cd80}, {0x7c6778, 0xc00008e700}, {0x0, 0x0, 0x0}) 3276s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3276s io.Copy(...) 3276s /usr/lib/go-1.24/src/io/io.go:388 3276s os/exec.(*Cmd).writerDescriptor.func1() 3276s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3276s os/exec.(*Cmd).Start.func2(0xc0003093b0?) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3276s created by os/exec.(*Cmd).Start in goroutine 12 3276s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3276s 3276s goroutine 280 [select]: 3276s os/exec.(*Cmd).watchCtx(0xc000212c00, 0xc000309500) 3276s /usr/lib/go-1.24/src/os/exec/exec.go:789 +0x94 3276s created by os/exec.(*Cmd).Start in goroutine 12 3276s /usr/lib/go-1.24/src/os/exec/exec.go:775 +0x740 3276s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 600.930s 3276s === RUN TestConnect 3276s === PAUSE TestConnect 3276s === RUN TestSendAndRecv 3276s --- PASS: TestSendAndRecv (1.54s) 3276s === RUN TestServe 3276s === PAUSE TestServe 3276s === RUN TestStop 3276s === PAUSE TestStop 3276s === RUN TestWithProMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithProMock (0.00s) 3276s === RUN TestWithWslPathMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithWslPathMock (0.01s) 3276s === RUN TestWithWslInfoMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithWslInfoMock (0.01s) 3276s === RUN TestWithCmdExeMock 3276s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3276s --- SKIP: TestWithCmdExeMock (0.00s) 3276s === CONT TestConnect 3276s === CONT TestStop 3276s === RUN TestConnect/Success 3276s === PAUSE TestConnect/Success 3276s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 3276s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 3276s === CONT TestServe 3276s time="2025-03-15T22:48:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44385" 3276s time="2025-03-15T22:48:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35669" 3276s === NAME TestStop 3276s server_test.go:111: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/streams/server_test.go:111 3276s Error: Condition never satisfied 3276s Test: TestStop 3276s Messages: Setup: Agent service never became ready 3276s === NAME TestServe 3276s server_test.go:41: 3276s Error Trace: /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/streams/server_test.go:41 3276s Error: Condition never satisfied 3276s Test: TestServe 3276s Messages: Setup: Agent service never became ready 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s time="2025-03-15T22:48:26Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 3276s --- FAIL: TestServe (21.42s) 3276s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 3276s === CONT TestConnect/Success 3276s --- FAIL: TestStop (21.41s) 3276s --- PASS: TestConnect (0.01s) 3276s --- PASS: TestConnect/Success (0.68s) 3276s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.33s) 3276s FAIL 3276s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 45.461s 3497s === RUN TestNew 3497s === PAUSE TestNew 3497s === RUN TestInfo 3497s === PAUSE TestInfo 3497s === RUN TestWslDistroName 3497s === PAUSE TestWslDistroName 3497s === RUN TestUserProfileDir 3497s === PAUSE TestUserProfileDir 3497s === RUN TestProStatus 3497s === PAUSE TestProStatus 3497s === RUN TestProAttach 3497s === PAUSE TestProAttach 3497s === RUN TestProDetach 3497s === PAUSE TestProDetach 3497s === RUN TestLandscapeEnable 3497s === PAUSE TestLandscapeEnable 3497s === RUN TestWindowsHostAddress 3497s === PAUSE TestWindowsHostAddress 3497s === RUN TestLandscapeDisable 3497s === PAUSE TestLandscapeDisable 3497s === RUN TestEnsureValidLandscapeConfig 3497s === PAUSE TestEnsureValidLandscapeConfig 3497s === RUN TestRealBackend 3497s === PAUSE TestRealBackend 3497s === RUN TestWithProMock 3497s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3497s --- SKIP: TestWithProMock (0.03s) 3497s === RUN TestWithLandscapeConfigMock 3497s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3497s --- SKIP: TestWithLandscapeConfigMock (0.00s) 3497s === RUN TestWithWslPathMock 3497s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3497s --- SKIP: TestWithWslPathMock (0.00s) 3497s === RUN TestWithWslInfoMock 3497s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3497s --- SKIP: TestWithWslInfoMock (0.03s) 3497s === RUN TestWithCmdExeMock 3497s mock_executables.go:645: Skipped because it is not a real test, but rather a mocked executable 3497s --- SKIP: TestWithCmdExeMock (0.01s) 3497s === CONT TestNew 3497s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 3497s === CONT TestProDetach 3497s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 3497s === RUN TestNew/Return_a_new_system 3497s === PAUSE TestNew/Return_a_new_system 3497s === RUN TestProDetach/success_on_unattached_distro 3497s === PAUSE TestProDetach/success_on_unattached_distro 3497s === CONT TestRealBackend 3497s === RUN TestProDetach/success_on_attached_distro 3497s === PAUSE TestProDetach/success_on_attached_distro 3497s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 3497s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 3497s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 3497s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 3497s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 3497s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 3497s === CONT TestEnsureValidLandscapeConfig 3497s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 3497s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 3497s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 3497s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 3497s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 3497s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 3497s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 3497s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 3497s --- PASS: TestRealBackend (0.05s) 3497s === CONT TestLandscapeDisable 3497s === RUN TestLandscapeDisable/Success 3497s === PAUSE TestLandscapeDisable/Success 3497s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 3497s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 3497s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 3497s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 3497s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 3497s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 3497s === CONT TestWindowsHostAddress 3497s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 3497s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 3497s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 3497s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 3497s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 3497s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 3497s === RUN TestWindowsHostAddress/Without_NAT 3497s === PAUSE TestWindowsHostAddress/Without_NAT 3497s === RUN TestWindowsHostAddress/With_NAT 3497s === PAUSE TestWindowsHostAddress/With_NAT 3497s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 3497s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 3497s === CONT TestLandscapeEnable 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 3497s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 3497s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 3497s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 3497s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 3497s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 3497s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 3497s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 3497s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 3497s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 3497s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 3497s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 3497s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 3497s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 3497s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 3497s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 3497s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 3497s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 3497s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 3497s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 3497s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 3497s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 3497s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 3497s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 3497s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 3497s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 3497s === CONT TestUserProfileDir 3497s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 3497s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 3497s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 3497s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 3497s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 3497s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 3497s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 3497s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 3497s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 3497s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 3497s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 3497s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 3497s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 3497s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 3497s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 3497s === RUN TestLandscapeEnable/Regular_with_additional_keys 3497s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 3497s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 3497s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 3497s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 3497s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 3497s === RUN TestUserProfileDir/Error_on_wslpath_error 3497s === PAUSE TestUserProfileDir/Error_on_wslpath_error 3497s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 3497s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 3497s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 3497s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 3497s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 3497s === CONT TestProAttach 3497s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 3497s === RUN TestProAttach/success 3497s === PAUSE TestProAttach/success 3497s === RUN TestProAttach/error_on_'pro_attach'_error 3497s === PAUSE TestProAttach/error_on_'pro_attach'_error 3497s === CONT TestProStatus 3497s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 3497s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 3497s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 3497s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 3497s === RUN TestUserProfileDir/Error_on_cmd.exe_error 3497s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 3497s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 3497s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 3497s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 3497s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 3497s === RUN TestProStatus/success_on_unattached_distro 3497s === PAUSE TestProStatus/success_on_unattached_distro 3497s === RUN TestProStatus/success_on_attached_distro 3497s === PAUSE TestProStatus/success_on_attached_distro 3497s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 3497s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 3497s === RUN TestProStatus/error_on_'pro_attach'_error 3497s === PAUSE TestProStatus/error_on_'pro_attach'_error 3497s === CONT TestWslDistroName 3497s === RUN TestWslDistroName/Success_using_wslpath 3497s === CONT TestInfo 3497s === RUN TestInfo/Error_when_pro_status_command_fails 3497s === PAUSE TestInfo/Error_when_pro_status_command_fails 3497s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 3497s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 3497s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 3497s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 3497s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 3497s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 3497s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 3497s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 3497s === RUN TestInfo/Success 3497s === PAUSE TestInfo/Success 3497s === RUN TestInfo/Error_when_WslDistroName_fails 3497s === PAUSE TestInfo/Error_when_WslDistroName_fails 3497s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 3497s === PAUSE TestWslDistroName/Success_using_wslpath 3497s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 3497s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 3497s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 3497s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 3497s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 3497s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 3497s === CONT TestNew/Return_a_new_system 3497s time="2025-03-15T22:50:42Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: could not register distro to Landscape: could not parse config: key-value delimiter not found: {\n" 3497s === CONT TestProDetach/success_on_unattached_distro 3497s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 3497s --- PASS: TestNew (0.02s) 3497s --- PASS: TestNew/Return_a_new_system (0.20s) 3497s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.27s) 3497s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 3497s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 3497s === CONT TestProDetach/success_on_attached_distro 3497s === CONT TestLandscapeDisable/Success 3497s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 3497s --- PASS: TestProDetach (0.03s) 3497s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (88.86s) 3497s --- PASS: TestProDetach/success_on_unattached_distro (90.61s) 3497s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (83.15s) 3497s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (82.18s) 3497s --- PASS: TestProDetach/success_on_attached_distro (72.00s) 3497s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 3497s === CONT TestWindowsHostAddress/Without_NAT 3497s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 3497s --- PASS: TestLandscapeDisable (0.00s) 3497s --- PASS: TestLandscapeDisable/Success (76.31s) 3497s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (70.39s) 3497s === CONT TestWindowsHostAddress/With_NAT 3497s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 3497s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 3497s === CONT TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 3497s === CONT TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 3497s --- PASS: TestWindowsHostAddress (0.00s) 3497s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (72.82s) 3497s --- PASS: TestWindowsHostAddress/Without_NAT (68.04s) 3497s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (67.47s) 3497s --- PASS: TestWindowsHostAddress/With_NAT (33.93s) 3497s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (33.91s) 3497s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (30.04s) 3497s === NAME TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 3497s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/appends_any_required_fields_-_no_ssl 3497s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 3497s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 3497s === NAME TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 3497s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/refresh_computer_title_if_changed 3497s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 3497s === NAME TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 3497s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 3497s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 3497s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 3497s === CONT TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 3497s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 3497s system_test.go:767: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 3497s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 3497s === CONT TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 3497s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 3497s panic: test timed out after 10m0s 3497s running tests: 3497s TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails (52s) 3497s TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (18s) 3497s 3497s goroutine 150 [running]: 3497s testing.(*M).startAlarm.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:2484 +0x304 3497s created by time.goFunc 3497s /usr/lib/go-1.24/src/time/sleep.go:215 +0x58 3497s 3497s goroutine 1 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018c380, 0xc00017bca8) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s testing.runTests(0xc00008e7c8, {0xb17420, 0x11, 0x11}, {0x1d704?, 0xc0002108a0?, 0xb24960?}) 3497s /usr/lib/go-1.24/src/testing/testing.go:2277 +0x440 3497s testing.(*M).Run(0xc00015fa40) 3497s /usr/lib/go-1.24/src/testing/testing.go:2142 +0x5bc 3497s main.main() 3497s _testmain.go:79 +0x94 3497s 3497s goroutine 19 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018c8c0, 0x660f70) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 20 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018cc40, 0x660fe8) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 21 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018cfc0, 0x660fb0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 22 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018d340, 0x660fa0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 23 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018d6c0, 0x660f90) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 25 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc00018ddc0, 0x660f80) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 28 [chan receive]: 3497s testing.tRunner.func1() 3497s /usr/lib/go-1.24/src/testing/testing.go:1753 +0x3c4 3497s testing.tRunner(0xc0001c48c0, 0x660f68) 3497s /usr/lib/go-1.24/src/testing/testing.go:1798 +0x134 3497s created by testing.(*T).Run in goroutine 1 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 50 [syscall]: 3497s syscall.Syscall6(0x5f, 0x3, 0x10, 0xc000139928, 0x4, 0xc0001977a0, 0x0) 3497s /usr/lib/go-1.24/src/syscall/syscall_linux.go:95 +0x2c 3497s internal/syscall/unix.Waitid(0xc000139956?, 0x175a3c?, 0xc000323880?, 0x1?, 0xc000335c50?) 3497s /usr/lib/go-1.24/src/internal/syscall/unix/waitid_linux.go:18 +0x70 3497s os.(*Process).pidfdWait.func1(...) 3497s /usr/lib/go-1.24/src/os/pidfd_linux.go:106 3497s os.ignoringEINTR(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:251 3497s os.(*Process).pidfdWait(0xc0002bf580) 3497s /usr/lib/go-1.24/src/os/pidfd_linux.go:105 +0x1b8 3497s os.(*Process).wait(0x7ff0e93e2108?) 3497s /usr/lib/go-1.24/src/os/exec_unix.go:27 +0x38 3497s os.(*Process).Wait(...) 3497s /usr/lib/go-1.24/src/os/exec.go:358 3497s os/exec.(*Cmd).Wait(0xc00035c300) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:922 +0x44 3497s os/exec.(*Cmd).Run(0xc00035c300) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:626 +0x44 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc00035c300) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:231 +0x140 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.overrideSSLCertificate({0x70aac8, 0xb45a40}, 0xc000335ad0, 0x633aa3?) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:179 +0x100 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.normalizeLandscapeConfig({0x70aac8, 0xb45a40}, 0xc000335ad0, 0xc00011ef00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:147 +0x148 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).fixAndEnableLandscapeFromConfig(0xc000335ad0, {0x70aac8, 0xb45a40}, {0xc000318b40, 0x119}, 0x0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:62 +0x190 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).EnsureValidLandscapeConfig(0xc000335ad0, {0x70aac8, 0xb45a40}) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:49 +0x1b0 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000236380) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:738 +0x60c 3497s testing.tRunner(0xc000236380, 0xc0000310b0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 35 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00022d500) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00022d500) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc00022d500, 0xc000030f60) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 36 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00022d880) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00022d880) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc00022d880, 0xc000030fc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 37 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00022dc00) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00022dc00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc00022dc00, 0xc000031010) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 38 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000236000) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000236000) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc000236000, 0xc000031060) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 51 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001c5c00) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0001c5c00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc0001c5c00, 0xc000095300) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 52 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001de000) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0001de000) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc0001de000, 0xc000095350) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 147 [IO wait]: 3497s internal/poll.runtime_pollWait(0x7ff0e9258b68, 0x72) 3497s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3497s internal/poll.(*pollDesc).wait(0xc00034ecc0?, 0xc00034dc00?, 0x1) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3497s internal/poll.(*pollDesc).waitRead(...) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3497s internal/poll.(*FD).Read(0xc00034ecc0, {0xc00034dc00, 0x200, 0x200}) 3497s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3497s os.(*File).read(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:29 3497s os.(*File).Read(0xc000052ef8, {0xc00034dc00?, 0xc49b4?, 0x578160?}) 3497s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3497s bytes.(*Buffer).ReadFrom(0xc000335830, {0x705058, 0xc000052f18}) 3497s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3497s io.copyBuffer({0x7051c0, 0xc000335830}, {0x705058, 0xc000052f18}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os.genericWriteTo(0xc000052ef8?, {0x7051c0, 0xc000335830}) 3497s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3497s os.(*File).WriteTo(0xc000052ef8, {0x7051c0, 0xc000335830}) 3497s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3497s io.copyBuffer({0x7051c0, 0xc000335830}, {0x7050d8, 0xc000052ef8}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os/exec.(*Cmd).writerDescriptor.func1() 3497s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3497s os/exec.(*Cmd).Start.func2(0x0?) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3497s created by os/exec.(*Cmd).Start in goroutine 56 3497s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3497s 3497s goroutine 53 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001de380) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0001de380) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:698 +0x38 3497s testing.tRunner(0xc0001de380, 0xc0000953a0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 148 [IO wait]: 3497s internal/poll.runtime_pollWait(0x7ff0e9258eb0, 0x72) 3497s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3497s internal/poll.(*pollDesc).wait(0xc00034f560?, 0xc00036a200?, 0x1) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3497s internal/poll.(*pollDesc).waitRead(...) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3497s internal/poll.(*FD).Read(0xc00034f560, {0xc00036a200, 0x200, 0x200}) 3497s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3497s os.(*File).read(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:29 3497s os.(*File).Read(0xc000052fe8, {0xc00036a200?, 0xc49b4?, 0x578160?}) 3497s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3497s bytes.(*Buffer).ReadFrom(0xc000335d40, {0x705058, 0xc000053018}) 3497s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3497s io.copyBuffer({0x7051c0, 0xc000335d40}, {0x705058, 0xc000053018}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os.genericWriteTo(0xc000052fe8?, {0x7051c0, 0xc000335d40}) 3497s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3497s os.(*File).WriteTo(0xc000052fe8, {0x7051c0, 0xc000335d40}) 3497s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3497s io.copyBuffer({0x7051c0, 0xc000335d40}, {0x7050d8, 0xc000052fe8}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os/exec.(*Cmd).writerDescriptor.func1() 3497s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3497s os/exec.(*Cmd).Start.func2(0x0?) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3497s created by os/exec.(*Cmd).Start in goroutine 50 3497s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3497s 3497s goroutine 56 [syscall]: 3497s syscall.Syscall6(0x5f, 0x3, 0xf, 0xc00013ba50, 0x4, 0xc0001975f0, 0x0) 3497s /usr/lib/go-1.24/src/syscall/syscall_linux.go:95 +0x2c 3497s internal/syscall/unix.Waitid(0xc00013ba7e?, 0x175a3c?, 0xc000323570?, 0x1?, 0xc000335710?) 3497s /usr/lib/go-1.24/src/internal/syscall/unix/waitid_linux.go:18 +0x70 3497s os.(*Process).pidfdWait.func1(...) 3497s /usr/lib/go-1.24/src/os/pidfd_linux.go:106 3497s os.ignoringEINTR(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:251 3497s os.(*Process).pidfdWait(0xc0002bf440) 3497s /usr/lib/go-1.24/src/os/pidfd_linux.go:105 +0x1b8 3497s os.(*Process).wait(0x7ff0e93e2108?) 3497s /usr/lib/go-1.24/src/os/exec_unix.go:27 +0x38 3497s os.(*Process).Wait(...) 3497s /usr/lib/go-1.24/src/os/exec.go:358 3497s os/exec.(*Cmd).Wait(0xc00035c180) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:922 +0x44 3497s os/exec.(*Cmd).Run(0xc00035c180) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:626 +0x44 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc00035c180) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system.go:231 +0x140 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).fixAndEnableLandscapeFromConfig(0xc000380450, {0x70aac8, 0xb45a40}, {0xc000318a20, 0x119}, 0x0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:79 +0x26c 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).EnsureValidLandscapeConfig(0xc000380450, {0x70aac8, 0xb45a40}) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/landscape.go:49 +0x1b0 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0001dee00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:738 +0x60c 3497s testing.tRunner(0xc0001dee00, 0xc000095490) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 28 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 146 [IO wait]: 3497s internal/poll.runtime_pollWait(0x7ff0e9258938, 0x72) 3497s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3497s internal/poll.(*pollDesc).wait(0xc00034ec00?, 0xc00034da00?, 0x1) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3497s internal/poll.(*pollDesc).waitRead(...) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3497s internal/poll.(*FD).Read(0xc00034ec00, {0xc00034da00, 0x200, 0x200}) 3497s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3497s os.(*File).read(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:29 3497s os.(*File).Read(0xc000052ee0, {0xc00034da00?, 0xc49b4?, 0x578160?}) 3497s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3497s bytes.(*Buffer).ReadFrom(0xc000335800, {0x705058, 0xc000052f10}) 3497s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3497s io.copyBuffer({0x7051c0, 0xc000335800}, {0x705058, 0xc000052f10}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os.genericWriteTo(0xc000052ee0?, {0x7051c0, 0xc000335800}) 3497s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3497s os.(*File).WriteTo(0xc000052ee0, {0x7051c0, 0xc000335800}) 3497s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3497s io.copyBuffer({0x7051c0, 0xc000335800}, {0x7050d8, 0xc000052ee0}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os/exec.(*Cmd).writerDescriptor.func1() 3497s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3497s os/exec.(*Cmd).Start.func2(0x0?) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3497s created by os/exec.(*Cmd).Start in goroutine 56 3497s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3497s 3497s goroutine 45 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000237a40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000237a40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000237a40, 0xc0000312b0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 46 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000237dc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000237dc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000237dc0, 0xc000031300) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 149 [IO wait]: 3497s internal/poll.runtime_pollWait(0x7ff0e9258a50, 0x72) 3497s /usr/lib/go-1.24/src/runtime/netpoll.go:351 +0xc0 3497s internal/poll.(*pollDesc).wait(0xc00034f620?, 0xc00036a400?, 0x1) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:84 +0x44 3497s internal/poll.(*pollDesc).waitRead(...) 3497s /usr/lib/go-1.24/src/internal/poll/fd_poll_runtime.go:89 3497s internal/poll.(*FD).Read(0xc00034f620, {0xc00036a400, 0x200, 0x200}) 3497s /usr/lib/go-1.24/src/internal/poll/fd_unix.go:165 +0x234 3497s os.(*File).read(...) 3497s /usr/lib/go-1.24/src/os/file_posix.go:29 3497s os.(*File).Read(0xc000053000, {0xc00036a400?, 0xc49b4?, 0x578160?}) 3497s /usr/lib/go-1.24/src/os/file.go:124 +0x90 3497s bytes.(*Buffer).ReadFrom(0xc000335d70, {0x705058, 0xc000053020}) 3497s /usr/lib/go-1.24/src/bytes/buffer.go:211 +0xa8 3497s io.copyBuffer({0x7051c0, 0xc000335d70}, {0x705058, 0xc000053020}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:415 +0x180 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os.genericWriteTo(0xc000053000?, {0x7051c0, 0xc000335d70}) 3497s /usr/lib/go-1.24/src/os/file.go:275 +0x74 3497s os.(*File).WriteTo(0xc000053000, {0x7051c0, 0xc000335d70}) 3497s /usr/lib/go-1.24/src/os/file.go:253 +0xc0 3497s io.copyBuffer({0x7051c0, 0xc000335d70}, {0x7050d8, 0xc000053000}, {0x0, 0x0, 0x0}) 3497s /usr/lib/go-1.24/src/io/io.go:411 +0xd4 3497s io.Copy(...) 3497s /usr/lib/go-1.24/src/io/io.go:388 3497s os/exec.(*Cmd).writerDescriptor.func1() 3497s /usr/lib/go-1.24/src/os/exec/exec.go:596 +0x48 3497s os/exec.(*Cmd).Start.func2(0x0?) 3497s /usr/lib/go-1.24/src/os/exec/exec.go:749 +0x40 3497s created by os/exec.(*Cmd).Start in goroutine 50 3497s /usr/lib/go-1.24/src/os/exec/exec.go:748 +0x778 3497s 3497s goroutine 47 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002421c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc0002421c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc0002421c0, 0xc000031360) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 48 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000242540) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000242540) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000242540, 0xc0000313b0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 49 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002428c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc0002428c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc0002428c0, 0xc000031400) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 66 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000242c40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000242c40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000242c40, 0xc000031450) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 67 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000242fc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000242fc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000242fc0, 0xc0000314a0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 68 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000243340) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000243340) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000243340, 0xc0000314f0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 69 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002436c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc0002436c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc0002436c0, 0xc000031540) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 70 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000243a40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000243a40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000243a40, 0xc000031590) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 71 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000243dc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc000243dc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc000243dc0, 0xc0000315e0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 62 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001ea380) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001ea380) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001ea380, 0xc00008b010) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 63 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001ea700) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001ea700) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001ea700, 0xc00008b040) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 72 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024e1c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc00024e1c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc00024e1c0, 0xc000031630) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 73 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024e540) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc00024e540) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc00024e540, 0xc000031680) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 74 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024e8c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc00024e8c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc00024e8c0, 0xc0000316d0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 75 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024ec40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc00024ec40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc00024ec40, 0xc000031720) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 76 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024efc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestLandscapeEnable.func1(0xc00024efc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:467 +0x38 3497s testing.tRunner(0xc00024efc0, 0xc000031770) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 25 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 64 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001eaa80) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001eaa80) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001eaa80, 0xc00008b070) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 65 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001eae00) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001eae00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001eae00, 0xc00008b0a0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 82 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001eb180) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001eb180) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001eb180, 0xc00008b0d0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 83 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001eb500) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0001eb500) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0001eb500, 0xc00008b100) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 77 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024f340) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProAttach.func1(0xc00024f340) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:357 +0x40 3497s testing.tRunner(0xc00024f340, 0xc00000f150) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 23 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 78 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024f6c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProAttach.func1(0xc00024f6c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:357 +0x40 3497s testing.tRunner(0xc00024f6c0, 0xc00000f170) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 23 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 79 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024fa40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc00024fa40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc00024fa40, 0xc00008b130) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 80 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc00024fdc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc00024fdc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc00024fdc0, 0xc00000f1c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 81 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002561c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0002561c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0002561c0, 0xc00000f1f0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 98 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000256540) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc000256540) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc000256540, 0xc00000f220) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 99 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002568c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestUserProfileDir.func1(0xc0002568c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:229 +0x34 3497s testing.tRunner(0xc0002568c0, 0xc00000f250) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 21 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 100 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000256c40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProStatus.func1(0xc000256c40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:314 +0x48 3497s testing.tRunner(0xc000256c40, 0xc0001a2588) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 22 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 101 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000256fc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProStatus.func1(0xc000256fc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:314 +0x48 3497s testing.tRunner(0xc000256fc0, 0xc000022630) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 22 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 102 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000257340) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProStatus.func1(0xc000257340) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:314 +0x48 3497s testing.tRunner(0xc000257340, 0xc000022648) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 22 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 103 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002576c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestProStatus.func1(0xc0002576c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:314 +0x48 3497s testing.tRunner(0xc0002576c0, 0xc000022660) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 22 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 104 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000257a40) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestWslDistroName.func1(0xc000257a40) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:162 +0x48 3497s testing.tRunner(0xc000257a40, 0xc000028a60) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 20 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 84 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001eb880) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc0001eb880) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc0001eb880, 0xc0000b4510) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 85 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0001ebc00) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc0001ebc00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc0001ebc00, 0xc0000b45a0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 86 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000280000) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc000280000) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc000280000, 0xc0000b4600) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 87 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000280380) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc000280380) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc000280380, 0xc0000b4660) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 88 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000280700) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc000280700) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc000280700, 0xc0000b46c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 89 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000280a80) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc000280a80) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc000280a80, 0xc0000b4750) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 90 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000280e00) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestInfo.func1(0xc000280e00) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:88 +0x34 3497s testing.tRunner(0xc000280e00, 0xc0000b4780) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 19 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 105 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000257dc0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestWslDistroName.func1(0xc000257dc0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:162 +0x48 3497s testing.tRunner(0xc000257dc0, 0xc000028aa0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 20 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 106 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc0002621c0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestWslDistroName.func1(0xc0002621c0) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:162 +0x48 3497s testing.tRunner(0xc0002621c0, 0xc000028ae0) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 20 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s 3497s goroutine 107 [chan receive]: 3497s testing.(*testState).waitParallel(0xc00018a690) 3497s /usr/lib/go-1.24/src/testing/testing.go:1926 +0xf8 3497s testing.(*T).Parallel(0xc000262540) 3497s /usr/lib/go-1.24/src/testing/testing.go:1578 +0x1cc 3497s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestWslDistroName.func1(0xc000262540) 3497s /tmp/autopkgtest.rpOsn9/build.xVp/src/internal/system/system_test.go:162 +0x48 3497s testing.tRunner(0xc000262540, 0xc000028b20) 3497s /usr/lib/go-1.24/src/testing/testing.go:1792 +0xf8 3497s created by testing.(*T).Run in goroutine 20 3497s /usr/lib/go-1.24/src/testing/testing.go:1851 +0x3a0 3497s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 601.988s 3497s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 3497s FAIL 3503s autopkgtest [23:00:48]: test command1: -----------------------] 3508s autopkgtest [23:00:53]: test command1: - - - - - - - - - - results - - - - - - - - - - 3508s command1 FAIL non-zero exit status 1 3515s autopkgtest [23:01:00]: @@@@@@@@@@@@@@@@@@@@ summary 3515s command1 FAIL non-zero exit status 1 3523s nova [W] Using flock in prodstack6-riscv64 3523s Creating nova instance adt-plucky-riscv64-wsl-pro-service-20250315-220225-juju-7f2275-prod-proposed-migration-environment-15-46ac3463-de29-495d-8275-33cfbbf570e3 from image adt/ubuntu-plucky-riscv64-server-20250315.img (UUID 306bcabb-e549-4f8c-9dd8-573d52716ea6)... 3523s nova [W] Timed out waiting for bb0c0936-410f-4979-a8c2-e94a547a3b70 to get deleted.