0s autopkgtest [15:00:53]: starting date and time: 2026-02-10 15:00:53+0000 0s autopkgtest [15:00:53]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [15:00:53]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.idm5o26l/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-s390x-6.secgroup --name adt-resolute-s390x-wsl-pro-service-20260210-150052-juju-7f2275-prod-proposed-migration-environment-15-1b6f06d7-d7a0-4d73-8b76-a93d812a143a --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-s390x-wsl-pro-service-20260210-150052-juju-7f2275-prod-proposed-migration-environment-15-1b6f06d7-d7a0-4d73-8b76-a93d812a143a from image adt/ubuntu-resolute-s390x-server-20260210.img (UUID 6b7378ab-57e8-4ce9-99f5-fc485e5dec1f)... 134s autopkgtest [15:03:07]: testbed dpkg architecture: s390x 135s autopkgtest [15:03:08]: testbed apt version: 3.1.15 135s autopkgtest [15:03:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 135s autopkgtest [15:03:08]: testbed release detected to be: None 138s autopkgtest [15:03:11]: updating testbed package index (apt update) 139s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 139s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 139s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 139s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 140s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [176 kB] 140s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [30.2 kB] 140s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1723 kB] 140s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [272 kB] 140s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [1628 kB] 140s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [8136 B] 144s Fetched 3962 kB in 4s (937 kB/s) 149s Reading package lists... 152s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 153s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 153s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 153s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 160s Reading package lists... 160s Reading package lists... 162s Building dependency tree... 163s Reading state information... 165s Calculating upgrade... 166s The following packages will be upgraded: 166s libbrotli1 libc-bin libc-gconv-modules-extra libc6 locales 166s ubuntu-kernel-accessories ubuntu-standard 167s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 8325 kB of archives. 167s After this operation, 4096 B disk space will be freed. 167s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libc-gconv-modules-extra s390x 2.42-2ubuntu5 [1369 kB] 167s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libc6 s390x 2.42-2ubuntu5 [1623 kB] 167s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libc-bin s390x 2.42-2ubuntu5 [674 kB] 168s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x locales all 2.42-2ubuntu5 [4255 kB] 168s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x ubuntu-standard s390x 1.565 [13.3 kB] 168s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libbrotli1 s390x 1.2.0-3 [377 kB] 168s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x ubuntu-kernel-accessories s390x 1.565 [13.1 kB] 172s dpkg-preconfigure: unable to re-open stdin: No such file or directory 172s Fetched 8325 kB in 1s (6765 kB/s) 178s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 88760 files and directories currently installed.) 178s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_s390x.deb ... 178s Unpacking libc-gconv-modules-extra:s390x (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 181s Setting up libc-gconv-modules-extra:s390x (2.42-2ubuntu5) ... 185s (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 ... 88760 files and directories currently installed.) 185s Preparing to unpack .../libc6_2.42-2ubuntu5_s390x.deb ... 185s Unpacking libc6:s390x (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 185s Setting up libc6:s390x (2.42-2ubuntu5) ... 187s (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 ... 88760 files and directories currently installed.) 187s Preparing to unpack .../libc-bin_2.42-2ubuntu5_s390x.deb ... 187s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 189s Setting up libc-bin (2.42-2ubuntu5) ... 189s (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 ... 88760 files and directories currently installed.) 189s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 190s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 194s Preparing to unpack .../ubuntu-standard_1.565_s390x.deb ... 195s Unpacking ubuntu-standard (1.565) over (1.564) ... 197s Preparing to unpack .../libbrotli1_1.2.0-3_s390x.deb ... 198s Unpacking libbrotli1:s390x (1.2.0-3) over (1.1.0-2build6) ... 200s Preparing to unpack .../ubuntu-kernel-accessories_1.565_s390x.deb ... 200s Unpacking ubuntu-kernel-accessories (1.565) over (1.564) ... 202s Setting up ubuntu-kernel-accessories (1.565) ... 203s Setting up libbrotli1:s390x (1.2.0-3) ... 204s Setting up ubuntu-standard (1.565) ... 204s Setting up locales (2.42-2ubuntu5) ... 210s Generating locales (this might take a while)... 219s en_US.UTF-8... done 219s Generation complete. 219s Processing triggers for libc-bin (2.42-2ubuntu5) ... 220s Processing triggers for systemd (259-1ubuntu3) ... 226s Processing triggers for man-db (2.13.1-1build1) ... 247s autopkgtest [15:05:00]: upgrading testbed (apt dist-upgrade and autopurge) 248s Reading package lists... 250s Building dependency tree... 250s Reading state information... 250s Calculating upgrade... 251s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 252s Reading package lists... 254s Building dependency tree... 254s Reading state information... 254s Solving dependencies... 255s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 261s autopkgtest [15:05:14]: testbed running kernel: Linux 6.19.0-3-generic #3-Ubuntu SMP Fri Jan 23 19:02:49 UTC 2026 262s autopkgtest [15:05:15]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 266s Get:1 http://ftpmaster.internal/ubuntu resolute/main wsl-pro-service 0.1.18 (dsc) [2064 B] 266s Get:2 http://ftpmaster.internal/ubuntu resolute/main wsl-pro-service 0.1.18 (tar) [1790 kB] 267s gpgv: Signature made Tue Jun 10 19:06:57 2025 UTC 267s gpgv: using RSA key 92FE4F8B40CADC4ECCD60C1F5961FDBE9C619809 267s gpgv: Can't check signature: No public key 267s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.18.dsc: no acceptable signature found 269s autopkgtest [15:05:22]: testing package wsl-pro-service version 0.1.18 270s autopkgtest [15:05:23]: build not needed 274s autopkgtest [15:05:27]: test command1: preparing testbed 275s Reading package lists... 276s Building dependency tree... 276s Reading state information... 276s Solving dependencies... 278s The following NEW packages will be installed: 278s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 278s cpp-15-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-apport 278s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 278s g++-15-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-15 gcc-15-s390x-linux-gnu 278s gcc-s390x-linux-gnu gettext golang-1.25-go golang-1.25-src golang-go 278s golang-src intltool-debian libarchive-zip-perl libasan8 libc-dev-bin 278s libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl 278s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 libitm1 278s liblsan0 libmpc3 libstdc++-15-dev libtool libtsan2 libubsan1 linux-libc-dev 278s m4 po-debconf rpcsvc-proto 278s 0 upgraded, 52 newly installed, 0 to remove and 0 not upgraded. 278s Need to get 104 MB of archives. 278s After this operation, 428 MB of additional disk space will be used. 278s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x m4 s390x 1.4.21-1 [225 kB] 278s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x autoconf all 2.72-3.1ubuntu2 [384 kB] 278s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x autotools-dev all 20240727.1build1 [43.4 kB] 278s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x automake all 1:1.18.1-3build1 [582 kB] 279s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x autopoint all 0.23.2-1 [620 kB] 279s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libc-dev-bin s390x 2.42-2ubuntu5 [22.9 kB] 279s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x linux-libc-dev s390x 6.19.0-3.3 [1839 kB] 279s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x libcrypt-dev s390x 1:4.5.1-1 [127 kB] 279s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x rpcsvc-proto s390x 1.4.3-1build1 [67.1 kB] 279s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libc6-dev s390x 2.42-2ubuntu5 [1696 kB] 279s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x libisl23 s390x 0.27-1build1 [702 kB] 279s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libmpc3 s390x 1.3.1-2 [57.4 kB] 279s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [10.2 MB] 280s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15 s390x 15.2.0-12ubuntu1 [1028 B] 280s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [5746 B] 280s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x cpp s390x 4:15.2.0-4ubuntu1 [22.4 kB] 280s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libcc1-0 s390x 16-20260208-1ubuntu1 [52.4 kB] 280s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x libgomp1 s390x 16-20260208-1ubuntu1 [160 kB] 280s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x libitm1 s390x 16-20260208-1ubuntu1 [31.0 kB] 280s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x libasan8 s390x 16-20260208-1ubuntu1 [3134 kB] 280s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x liblsan0 s390x 16-20260208-1ubuntu1 [1372 kB] 280s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libtsan2 s390x 16-20260208-1ubuntu1 [2731 kB] 280s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x libubsan1 s390x 16-20260208-1ubuntu1 [1225 kB] 281s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-15-dev s390x 15.2.0-12ubuntu1 [1294 kB] 281s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [19.9 MB] 282s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15 s390x 15.2.0-12ubuntu1 [519 kB] 282s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [1208 B] 282s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x gcc s390x 4:15.2.0-4ubuntu1 [5018 B] 282s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++-15-dev s390x 15.2.0-12ubuntu1 [2660 kB] 282s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15-s390x-linux-gnu s390x 15.2.0-12ubuntu1 [11.7 MB] 282s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15 s390x 15.2.0-12ubuntu1 [25.3 kB] 282s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x g++-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [956 B] 282s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x g++ s390x 4:15.2.0-4ubuntu1 [1078 B] 282s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x build-essential s390x 12.12ubuntu2 [5258 B] 282s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x libdebhelper-perl all 13.28ubuntu1 [97.4 kB] 282s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x libtool all 2.5.4-9 [169 kB] 282s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x dh-autoreconf all 21+nmu1 [11.9 kB] 282s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 282s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB] 282s Get:40 http://ftpmaster.internal/ubuntu resolute/main s390x dh-strip-nondeterminism all 1.15.0-1build1 [5110 B] 282s Get:41 http://ftpmaster.internal/ubuntu resolute/main s390x debugedit s390x 1:5.2-3build1 [52.7 kB] 282s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x dwz s390x 0.16-2build1 [121 kB] 282s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x gettext s390x 0.23.2-1 [1062 kB] 282s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 282s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x po-debconf all 1.0.22 [215 kB] 282s Get:46 http://ftpmaster.internal/ubuntu resolute/main s390x debhelper all 13.28ubuntu1 [916 kB] 282s Get:47 http://ftpmaster.internal/ubuntu resolute/universe s390x dh-apport all 2.33.1-0ubuntu3 [20.2 kB] 282s Get:48 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 283s Get:49 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-go s390x 1.25.0-0ubuntu1 [17.1 MB] 284s Get:50 http://ftpmaster.internal/ubuntu resolute/main s390x golang-src all 2:1.25~1 [5206 B] 284s Get:51 http://ftpmaster.internal/ubuntu resolute/main s390x golang-go s390x 2:1.25~1 [44.1 kB] 284s Get:52 http://ftpmaster.internal/ubuntu resolute/main s390x dh-golang all 1.63build1 [25.0 kB] 287s Fetched 104 MB in 6s (16.8 MB/s) 287s Selecting previously unselected package m4. 287s (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 ... 88760 files and directories currently installed.) 287s Preparing to unpack .../00-m4_1.4.21-1_s390x.deb ... 287s Unpacking m4 (1.4.21-1) ... 288s Selecting previously unselected package autoconf. 288s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu2_all.deb ... 288s Unpacking autoconf (2.72-3.1ubuntu2) ... 288s Selecting previously unselected package autotools-dev. 288s Preparing to unpack .../02-autotools-dev_20240727.1build1_all.deb ... 288s Unpacking autotools-dev (20240727.1build1) ... 288s Selecting previously unselected package automake. 288s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ... 288s Unpacking automake (1:1.18.1-3build1) ... 288s Selecting previously unselected package autopoint. 288s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ... 288s Unpacking autopoint (0.23.2-1) ... 289s Selecting previously unselected package libc-dev-bin. 289s Preparing to unpack .../05-libc-dev-bin_2.42-2ubuntu5_s390x.deb ... 289s Unpacking libc-dev-bin (2.42-2ubuntu5) ... 289s Selecting previously unselected package linux-libc-dev:s390x. 290s Preparing to unpack .../06-linux-libc-dev_6.19.0-3.3_s390x.deb ... 290s Unpacking linux-libc-dev:s390x (6.19.0-3.3) ... 291s Selecting previously unselected package libcrypt-dev:s390x. 291s Preparing to unpack .../07-libcrypt-dev_1%3a4.5.1-1_s390x.deb ... 291s Unpacking libcrypt-dev:s390x (1:4.5.1-1) ... 291s Selecting previously unselected package rpcsvc-proto. 291s Preparing to unpack .../08-rpcsvc-proto_1.4.3-1build1_s390x.deb ... 291s Unpacking rpcsvc-proto (1.4.3-1build1) ... 291s Selecting previously unselected package libc6-dev:s390x. 291s Preparing to unpack .../09-libc6-dev_2.42-2ubuntu5_s390x.deb ... 291s Unpacking libc6-dev:s390x (2.42-2ubuntu5) ... 291s Selecting previously unselected package libisl23:s390x. 291s Preparing to unpack .../10-libisl23_0.27-1build1_s390x.deb ... 291s Unpacking libisl23:s390x (0.27-1build1) ... 292s Selecting previously unselected package libmpc3:s390x. 292s Preparing to unpack .../11-libmpc3_1.3.1-2_s390x.deb ... 292s Unpacking libmpc3:s390x (1.3.1-2) ... 292s Selecting previously unselected package cpp-15-s390x-linux-gnu. 292s Preparing to unpack .../12-cpp-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 292s Unpacking cpp-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 294s Selecting previously unselected package cpp-15. 294s Preparing to unpack .../13-cpp-15_15.2.0-12ubuntu1_s390x.deb ... 294s Unpacking cpp-15 (15.2.0-12ubuntu1) ... 294s Selecting previously unselected package cpp-s390x-linux-gnu. 294s Preparing to unpack .../14-cpp-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 294s Unpacking cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 294s Selecting previously unselected package cpp. 294s Preparing to unpack .../15-cpp_4%3a15.2.0-4ubuntu1_s390x.deb ... 294s Unpacking cpp (4:15.2.0-4ubuntu1) ... 294s Selecting previously unselected package libcc1-0:s390x. 295s Preparing to unpack .../16-libcc1-0_16-20260208-1ubuntu1_s390x.deb ... 295s Unpacking libcc1-0:s390x (16-20260208-1ubuntu1) ... 295s Selecting previously unselected package libgomp1:s390x. 295s Preparing to unpack .../17-libgomp1_16-20260208-1ubuntu1_s390x.deb ... 295s Unpacking libgomp1:s390x (16-20260208-1ubuntu1) ... 295s Selecting previously unselected package libitm1:s390x. 295s Preparing to unpack .../18-libitm1_16-20260208-1ubuntu1_s390x.deb ... 295s Unpacking libitm1:s390x (16-20260208-1ubuntu1) ... 295s Selecting previously unselected package libasan8:s390x. 295s Preparing to unpack .../19-libasan8_16-20260208-1ubuntu1_s390x.deb ... 295s Unpacking libasan8:s390x (16-20260208-1ubuntu1) ... 296s Selecting previously unselected package liblsan0:s390x. 296s Preparing to unpack .../20-liblsan0_16-20260208-1ubuntu1_s390x.deb ... 296s Unpacking liblsan0:s390x (16-20260208-1ubuntu1) ... 296s Selecting previously unselected package libtsan2:s390x. 297s Preparing to unpack .../21-libtsan2_16-20260208-1ubuntu1_s390x.deb ... 297s Unpacking libtsan2:s390x (16-20260208-1ubuntu1) ... 297s Selecting previously unselected package libubsan1:s390x. 297s Preparing to unpack .../22-libubsan1_16-20260208-1ubuntu1_s390x.deb ... 297s Unpacking libubsan1:s390x (16-20260208-1ubuntu1) ... 297s Selecting previously unselected package libgcc-15-dev:s390x. 298s Preparing to unpack .../23-libgcc-15-dev_15.2.0-12ubuntu1_s390x.deb ... 298s Unpacking libgcc-15-dev:s390x (15.2.0-12ubuntu1) ... 299s Selecting previously unselected package gcc-15-s390x-linux-gnu. 299s Preparing to unpack .../24-gcc-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 299s Unpacking gcc-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 303s Selecting previously unselected package gcc-15. 303s Preparing to unpack .../25-gcc-15_15.2.0-12ubuntu1_s390x.deb ... 303s Unpacking gcc-15 (15.2.0-12ubuntu1) ... 303s Selecting previously unselected package gcc-s390x-linux-gnu. 303s Preparing to unpack .../26-gcc-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 303s Unpacking gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 303s Selecting previously unselected package gcc. 303s Preparing to unpack .../27-gcc_4%3a15.2.0-4ubuntu1_s390x.deb ... 303s Unpacking gcc (4:15.2.0-4ubuntu1) ... 303s Selecting previously unselected package libstdc++-15-dev:s390x. 303s Preparing to unpack .../28-libstdc++-15-dev_15.2.0-12ubuntu1_s390x.deb ... 303s Unpacking libstdc++-15-dev:s390x (15.2.0-12ubuntu1) ... 305s Selecting previously unselected package g++-15-s390x-linux-gnu. 305s Preparing to unpack .../29-g++-15-s390x-linux-gnu_15.2.0-12ubuntu1_s390x.deb ... 305s Unpacking g++-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 309s Selecting previously unselected package g++-15. 309s Preparing to unpack .../30-g++-15_15.2.0-12ubuntu1_s390x.deb ... 309s Unpacking g++-15 (15.2.0-12ubuntu1) ... 309s Selecting previously unselected package g++-s390x-linux-gnu. 309s Preparing to unpack .../31-g++-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 309s Unpacking g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 309s Selecting previously unselected package g++. 309s Preparing to unpack .../32-g++_4%3a15.2.0-4ubuntu1_s390x.deb ... 309s Unpacking g++ (4:15.2.0-4ubuntu1) ... 309s Selecting previously unselected package build-essential. 309s Preparing to unpack .../33-build-essential_12.12ubuntu2_s390x.deb ... 309s Unpacking build-essential (12.12ubuntu2) ... 309s Selecting previously unselected package libdebhelper-perl. 309s Preparing to unpack .../34-libdebhelper-perl_13.28ubuntu1_all.deb ... 309s Unpacking libdebhelper-perl (13.28ubuntu1) ... 309s Selecting previously unselected package libtool. 309s Preparing to unpack .../35-libtool_2.5.4-9_all.deb ... 309s Unpacking libtool (2.5.4-9) ... 310s Selecting previously unselected package dh-autoreconf. 310s Preparing to unpack .../36-dh-autoreconf_21+nmu1_all.deb ... 310s Unpacking dh-autoreconf (21+nmu1) ... 310s Selecting previously unselected package libarchive-zip-perl. 310s Preparing to unpack .../37-libarchive-zip-perl_1.68-1_all.deb ... 310s Unpacking libarchive-zip-perl (1.68-1) ... 310s Selecting previously unselected package libfile-stripnondeterminism-perl. 310s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ... 310s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ... 310s Selecting previously unselected package dh-strip-nondeterminism. 310s Preparing to unpack .../39-dh-strip-nondeterminism_1.15.0-1build1_all.deb ... 310s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ... 310s Selecting previously unselected package debugedit. 310s Preparing to unpack .../40-debugedit_1%3a5.2-3build1_s390x.deb ... 310s Unpacking debugedit (1:5.2-3build1) ... 310s Selecting previously unselected package dwz. 310s Preparing to unpack .../41-dwz_0.16-2build1_s390x.deb ... 310s Unpacking dwz (0.16-2build1) ... 310s Selecting previously unselected package gettext. 310s Preparing to unpack .../42-gettext_0.23.2-1_s390x.deb ... 310s Unpacking gettext (0.23.2-1) ... 311s Selecting previously unselected package intltool-debian. 311s Preparing to unpack .../43-intltool-debian_0.35.0+20060710.6build1_all.deb ... 312s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 312s Selecting previously unselected package po-debconf. 312s Preparing to unpack .../44-po-debconf_1.0.22_all.deb ... 312s Unpacking po-debconf (1.0.22) ... 312s Selecting previously unselected package debhelper. 312s Preparing to unpack .../45-debhelper_13.28ubuntu1_all.deb ... 312s Unpacking debhelper (13.28ubuntu1) ... 312s Selecting previously unselected package dh-apport. 312s Preparing to unpack .../46-dh-apport_2.33.1-0ubuntu3_all.deb ... 312s Unpacking dh-apport (2.33.1-0ubuntu3) ... 313s Selecting previously unselected package golang-1.25-src. 313s Preparing to unpack .../47-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 313s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 326s Selecting previously unselected package golang-1.25-go. 326s Preparing to unpack .../48-golang-1.25-go_1.25.0-0ubuntu1_s390x.deb ... 326s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 329s Selecting previously unselected package golang-src. 329s Preparing to unpack .../49-golang-src_2%3a1.25~1_all.deb ... 329s Unpacking golang-src (2:1.25~1) ... 330s Selecting previously unselected package golang-go:s390x. 330s Preparing to unpack .../50-golang-go_2%3a1.25~1_s390x.deb ... 330s Unpacking golang-go:s390x (2:1.25~1) ... 330s Selecting previously unselected package dh-golang. 330s Preparing to unpack .../51-dh-golang_1.63build1_all.deb ... 330s Unpacking dh-golang (1.63build1) ... 331s Setting up libarchive-zip-perl (1.68-1) ... 331s Setting up libdebhelper-perl (13.28ubuntu1) ... 331s Setting up linux-libc-dev:s390x (6.19.0-3.3) ... 331s Setting up m4 (1.4.21-1) ... 331s Setting up libgomp1:s390x (16-20260208-1ubuntu1) ... 331s Setting up dh-apport (2.33.1-0ubuntu3) ... 331s Setting up autotools-dev (20240727.1build1) ... 331s Setting up rpcsvc-proto (1.4.3-1build1) ... 331s Setting up libmpc3:s390x (1.3.1-2) ... 331s Setting up autopoint (0.23.2-1) ... 331s Setting up autoconf (2.72-3.1ubuntu2) ... 331s Setting up libubsan1:s390x (16-20260208-1ubuntu1) ... 331s Setting up dwz (0.16-2build1) ... 331s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 331s Setting up libcrypt-dev:s390x (1:4.5.1-1) ... 331s Setting up libasan8:s390x (16-20260208-1ubuntu1) ... 331s Setting up debugedit (1:5.2-3build1) ... 331s Setting up libtsan2:s390x (16-20260208-1ubuntu1) ... 331s Setting up libisl23:s390x (0.27-1build1) ... 331s Setting up libc-dev-bin (2.42-2ubuntu5) ... 331s Setting up golang-src (2:1.25~1) ... 331s Setting up libcc1-0:s390x (16-20260208-1ubuntu1) ... 331s Setting up liblsan0:s390x (16-20260208-1ubuntu1) ... 331s Setting up libitm1:s390x (16-20260208-1ubuntu1) ... 331s Setting up automake (1:1.18.1-3build1) ... 332s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 332s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ... 332s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 332s Setting up gettext (0.23.2-1) ... 332s Setting up libgcc-15-dev:s390x (15.2.0-12ubuntu1) ... 332s Setting up golang-go:s390x (2:1.25~1) ... 332s Setting up intltool-debian (0.35.0+20060710.6build1) ... 332s Setting up dh-strip-nondeterminism (1.15.0-1build1) ... 332s Setting up cpp-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 332s Setting up libc6-dev:s390x (2.42-2ubuntu5) ... 332s Setting up gcc-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 332s Setting up cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 332s Setting up po-debconf (1.0.22) ... 332s Setting up libstdc++-15-dev:s390x (15.2.0-12ubuntu1) ... 332s Setting up gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 332s Setting up cpp-15 (15.2.0-12ubuntu1) ... 332s Setting up cpp (4:15.2.0-4ubuntu1) ... 333s Setting up g++-15-s390x-linux-gnu (15.2.0-12ubuntu1) ... 333s Setting up g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 333s Setting up gcc-15 (15.2.0-12ubuntu1) ... 333s Setting up g++-15 (15.2.0-12ubuntu1) ... 333s Setting up libtool (2.5.4-9) ... 333s Setting up gcc (4:15.2.0-4ubuntu1) ... 333s Setting up dh-autoreconf (21+nmu1) ... 333s Setting up g++ (4:15.2.0-4ubuntu1) ... 333s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 333s Setting up build-essential (12.12ubuntu2) ... 333s Setting up debhelper (13.28ubuntu1) ... 333s Setting up dh-golang (1.63build1) ... 333s Processing triggers for man-db (2.13.1-1build1) ... 346s Processing triggers for install-info (7.2-5) ... 346s Processing triggers for libc-bin (2.42-2ubuntu5) ... 350s autopkgtest [15:06:43]: test command1: ./debian/tests/test 350s autopkgtest [15:06:43]: test command1: [----------------------- 697s === RUN TestRunSignal 697s === RUN TestRunSignal/Send_SIGINT_exits 697s INFO Starting WSL Pro Service version Dev 697s === RUN TestRunSignal/Send_SIGTERM_exits 697s INFO Starting WSL Pro Service version Dev 697s --- PASS: TestRunSignal (0.38s) 697s --- PASS: TestRunSignal/Send_SIGINT_exits (0.23s) 697s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.15s) 697s === RUN TestRun 697s === PAUSE TestRun 697s === CONT TestRun 697s === RUN TestRun/Run_and_exit_successfully 697s === PAUSE TestRun/Run_and_exit_successfully 697s === RUN TestRun/Run_and_return_error 697s === PAUSE TestRun/Run_and_return_error 697s === RUN TestRun/Run_and_return_usage_error 697s === PAUSE TestRun/Run_and_return_usage_error 697s === RUN TestRun/Run_and_usage_error_only_does_not_fail 697s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 697s === CONT TestRun/Run_and_exit_successfully 697s === CONT TestRun/Run_and_return_usage_error 697s INFO Starting WSL Pro Service version Dev 697s INFO Starting WSL Pro Service version Dev 697s === CONT TestRun/Run_and_usage_error_only_does_not_fail 697s ERROR context.Background Error requested 697s === CONT TestRun/Run_and_return_error 697s INFO Starting WSL Pro Service version Dev 697s INFO Starting WSL Pro Service version Dev 697s ERROR context.Background Error requested 697s --- PASS: TestRun (0.00s) 697s --- PASS: TestRun/Run_and_exit_successfully (0.11s) 697s --- PASS: TestRun/Run_and_return_usage_error (0.11s) 697s --- PASS: TestRun/Run_and_return_error (0.18s) 697s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.21s) 697s PASS 697s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.835s 810s === RUN TestHelp 810s --- PASS: TestHelp (0.00s) 810s === RUN TestCompletion 810s --- PASS: TestCompletion (0.00s) 810s === RUN TestVersion 810s --- PASS: TestVersion (0.00s) 810s === RUN TestConfigBadArg 810s --- PASS: TestConfigBadArg (0.00s) 810s === RUN TestConfigArg 810s --- PASS: TestConfigArg (0.00s) 810s === RUN TestConfigAutoDetect 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 810s --- PASS: TestConfigAutoDetect (0.00s) 810s === RUN TestNoUsageError 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 810s --- PASS: TestNoUsageError (0.00s) 810s === RUN TestUsageError 810s === PAUSE TestUsageError 810s === RUN TestCanQuitWhenExecute 810s === PAUSE TestCanQuitWhenExecute 810s === RUN TestCanQuitTwice 810s === PAUSE TestCanQuitTwice 810s === RUN TestAppCanQuitWithoutExecute 810s === PAUSE TestAppCanQuitWithoutExecute 810s === RUN TestAppRunFailsOnComponentsCreationAndQuit 810s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 810s === RUN TestAppGetRootCmd 810s === PAUSE TestAppGetRootCmd 810s === RUN TestWithProMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithProMock (0.00s) 810s === RUN TestWithWslPathMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithWslPathMock (0.00s) 810s === RUN TestWithWslInfoMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithWslInfoMock (0.00s) 810s === RUN TestWithCmdExeMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithCmdExeMock (0.00s) 810s === CONT TestUsageError 810s --- PASS: TestUsageError (0.00s) 810s === CONT TestAppGetRootCmd 810s === CONT TestAppRunFailsOnComponentsCreationAndQuit 810s time="2026-02-10T15:14:20Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41267" 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:74 New() Building new daemon" 810s --- PASS: TestAppGetRootCmd (0.04s) 810s === CONT TestCanQuitTwice 810s time="2026-02-10T15:14:20Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44849" 810s time="2026-02-10T15:14:20Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build60833371/b386/wsl-pro-service.yaml" 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 810s time="2026-02-10T15:14:20Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:74 New() Building new daemon" 810s time="2026-02-10T15:14:59Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 810s time="2026-02-10T15:14:59Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:14:59Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:15:00Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 810s time="2026-02-10T15:15:00Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7273" 810s time="2026-02-10T15:15:04Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 810s time="2026-02-10T15:15:05Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 810s time="2026-02-10T15:15:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7273" 810s time="2026-02-10T15:15:22Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:44849" 810s time="2026-02-10T15:15:22Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 810s time="2026-02-10T15:15:22Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.LandscapeConfigCmd requests" 810s time="2026-02-10T15:15:22Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:191 (*handlingLoop[...]).run() Stopping serving agentapi.ProAttachCmd requests" 810s time="2026-02-10T15:15:24Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:15:24Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:15:24Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s === CONT TestAppCanQuitWithoutExecute 810s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (63.51s) 810s === NAME TestAppCanQuitWithoutExecute 810s service_test.go:190: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 810s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 810s === CONT TestCanQuitWhenExecute 810s time="2026-02-10T15:15:24Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37133" 810s time="2026-02-10T15:15:24Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/config.go:53 initViperConfig() Using configuration file: /tmp/go-build60833371/b386/wsl-pro-service.yaml" 810s time="2026-02-10T15:15:24Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/cmd/wsl-pro-service/service/service.go:64 New.func1() Debug mode is enabled" 810s time="2026-02-10T15:15:24Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:74 New() Building new daemon" 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 810s time="2026-02-10T15:15:40Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:146 (*Server).Serve() Server: sent preface messages to all streams" 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 810s time="2026-02-10T15:15:40Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 810s time="2026-02-10T15:15:40Z" level=warning msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:196 (*Daemon).serveOnce() Daemon: disconnected from Windows host" 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 810s time="2026-02-10T15:15:40Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:15:40Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:15:40Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s --- PASS: TestCanQuitTwice (79.43s) 810s time="2026-02-10T15:15:40Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 810s time="2026-02-10T15:15:40Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 810s time="2026-02-10T15:15:40Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/github.com/ubuntu/decorate/decorate.go:31" 810s time="2026-02-10T15:15:54Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:55 (*System).syncWithCloudInit() Checking cloud-init status" 810s time="2026-02-10T15:15:54Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:45 (*System).EnsureValidLandscapeConfig() No Landscape configuration detected: nothing to do" 810s time="2026-02-10T15:15:54Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:163 (*Daemon).serveOnce() Daemon: connecting to Windows Agent from PID 7273" 810s time="2026-02-10T15:15:55Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:15:55Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:16:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:282 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:37133" 810s time="2026-02-10T15:16:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:183 (*Daemon).serveOnce() Daemon: completed connection to Windows Agent" 810s time="2026-02-10T15:16:05Z" level=warning msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:194 (*Daemon).serveOnce() 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:37133: connect: connection refused\"" 810s time="2026-02-10T15:16:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:144 (*Daemon).Serve.func2() Reconnecting to Windows host in 1 seconds" 810s time="2026-02-10T15:16:05Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s time="2026-02-10T15:16:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:215 (*Daemon).Quit() Stopping daemon requested." 810s time="2026-02-10T15:16:05Z" level=info msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:221 (*Daemon).Quit() Waiting for active requests to close." 810s time="2026-02-10T15:16:05Z" level=debug msg="/tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:229 (*Daemon).Quit() All connections have now ended." 810s --- PASS: TestCanQuitWhenExecute (41.31s) 810s PASS 810s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 105.142s 810s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 810s === RUN TestApplyProToken 810s === PAUSE TestApplyProToken 810s === RUN TestApplyLandscapeConfig 810s === PAUSE TestApplyLandscapeConfig 810s === RUN TestWithProMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithProMock (0.00s) 810s === RUN TestWithLandscapeConfigMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithLandscapeConfigMock (0.00s) 810s === RUN TestWithWslPathMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithWslPathMock (0.00s) 810s === RUN TestWithWslInfoMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithWslInfoMock (0.00s) 810s === RUN TestWithCmdExeMock 810s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 810s --- SKIP: TestWithCmdExeMock (0.00s) 810s === CONT TestApplyProToken 810s === RUN TestApplyProToken/Error_calling_pro_detach 810s === PAUSE TestApplyProToken/Error_calling_pro_detach 810s === RUN TestApplyProToken/Error_calling_pro_attach 810s === PAUSE TestApplyProToken/Error_calling_pro_attach 810s === RUN TestApplyProToken/Success_attaching 810s === PAUSE TestApplyProToken/Success_attaching 810s === RUN TestApplyProToken/Success_detaching 810s === PAUSE TestApplyProToken/Success_detaching 810s === CONT TestApplyProToken/Error_calling_pro_detach 810s time="2026-02-10T15:14:30Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 810s === CONT TestApplyLandscapeConfig 810s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 810s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 810s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 810s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 810s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 810s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 810s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 810s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 810s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 810s time="2026-02-10T15:14:30Z" level=info msg="ApplyLandscapeConfig: received config: registering" 810s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 810s time="2026-02-10T15:14:47Z" level=info msg="ApplyLandscapeConfig: received config: registering" 810s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 810s time="2026-02-10T15:14:47Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 810s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 810s time="2026-02-10T15:15:02Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 810s === CONT TestApplyProToken/Success_attaching 810s time="2026-02-10T15:15:04Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 810s --- PASS: TestApplyLandscapeConfig (0.00s) 810s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (17.89s) 810s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (14.48s) 810s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (16.32s) 810s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (19.70s) 810s === CONT TestApplyProToken/Success_detaching 810s time="2026-02-10T15:15:22Z" level=info msg="ApplyProToken: Received empty token: detaching" 810s === CONT TestApplyProToken/Error_calling_pro_attach 810s time="2026-02-10T15:15:37Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 810s --- PASS: TestApplyProToken (0.00s) 810s --- PASS: TestApplyProToken/Error_calling_pro_detach (17.84s) 810s --- PASS: TestApplyProToken/Success_attaching (33.15s) 810s --- PASS: TestApplyProToken/Success_detaching (16.38s) 810s --- PASS: TestApplyProToken/Error_calling_pro_attach (19.51s) 810s PASS 810s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 87.270s 810s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 1412s === RUN TestNew 1412s === PAUSE TestNew 1412s === RUN TestServe 1412s === PAUSE TestServe 1412s === RUN TestServeAndQuit 1412s === PAUSE TestServeAndQuit 1412s === RUN TestRetryLogic 1412s === PAUSE TestRetryLogic 1412s === RUN TestReconnection 1412s === PAUSE TestReconnection 1412s === RUN TestWithProMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithProMock (0.00s) 1412s === RUN TestWithWslPathMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithWslPathMock (0.00s) 1412s === RUN TestWithWslInfoMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithWslInfoMock (0.00s) 1412s === RUN TestWithCmdExeMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithCmdExeMock (0.00s) 1412s === CONT TestNew 1412s === RUN TestNew/Success 1412s === PAUSE TestNew/Success 1412s === RUN TestNew/Error_when_WslPath_returns_error 1412s === PAUSE TestNew/Error_when_WslPath_returns_error 1412s === CONT TestNew/Success 1412s === CONT TestReconnection 1412s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1412s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1412s === RUN TestReconnection/Success_connecting_after_failing_to_connect 1412s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 1412s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 1412s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 1412s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1412s time="2026-02-10T15:16:06Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:16:06Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:16:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45549" 1412s time="2026-02-10T15:16:34Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:16:34Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:16:34Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:16:34Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:16:34Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s === CONT TestRetryLogic 1412s === RUN TestRetryLogic/Without_retries 1412s === PAUSE TestRetryLogic/Without_retries 1412s === RUN TestRetryLogic/With_the_context_pre-cancelled 1412s === PAUSE TestRetryLogic/With_the_context_pre-cancelled 1412s === RUN TestRetryLogic/With_the_context_cancelled_while_waiting 1412s === PAUSE TestRetryLogic/With_the_context_cancelled_while_waiting 1412s === RUN TestRetryLogic/When_max_attempts_are_exhausted 1412s === PAUSE TestRetryLogic/When_max_attempts_are_exhausted 1412s === RUN TestRetryLogic/Error_only_when_action_errors 1412s === PAUSE TestRetryLogic/Error_only_when_action_errors 1412s === CONT TestServeAndQuit 1412s === RUN TestServeAndQuit/Success_with_graceful_quit 1412s === PAUSE TestServeAndQuit/Success_with_graceful_quit 1412s === RUN TestServeAndQuit/Success_with_forceful_quit 1412s === PAUSE TestServeAndQuit/Success_with_forceful_quit 1412s === RUN TestServeAndQuit/Success_with_double_quit 1412s === PAUSE TestServeAndQuit/Success_with_double_quit 1412s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 1412s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 1412s === CONT TestServe 1412s === RUN TestServe/No_connection_because_the_port_file_has_port_0 1412s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 1412s === RUN TestServe/No_connection_because_there_are_no_certificates 1412s === PAUSE TestServe/No_connection_because_there_are_no_certificates 1412s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1412s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1412s === RUN TestServe/Error_because_the_context_is_pre-cancelled 1412s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 1412s === RUN TestServe/Error_because_the_notifier_returns_an_error 1412s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 1412s === RUN TestServe/Success_with_systemd_notifier_returning_true 1412s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 1412s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 1412s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 1412s === RUN TestServe/No_connection_because_the_port_file_is_empty 1412s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 1412s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 1412s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 1412s === RUN TestServe/No_connection_because_there_is_no_server 1412s === PAUSE TestServe/No_connection_because_there_is_no_server 1412s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 1412s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 1412s === RUN TestServe/Success 1412s === PAUSE TestServe/Success 1412s === RUN TestServe/Success_with_a_broken_Landscape_config 1412s === PAUSE TestServe/Success_with_a_broken_Landscape_config 1412s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 1412s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 1412s === CONT TestNew/Error_when_WslPath_returns_error 1412s time="2026-02-10T15:16:36Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:16:45Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45549" 1412s time="2026-02-10T15:16:45Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:16:45Z" level=debug msg="Updated systemd status to \"Connected\"" 1412s time="2026-02-10T15:16:45Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:16:45Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s === NAME TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1412s daemon_test.go:448: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:448 1412s Error: Condition never satisfied 1412s Test: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 1412s Messages: Daemon never connected to agent's service 1412s time="2026-02-10T15:16:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:16:56Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:16:56Z" level=info msg="Stopping active requests." 1412s time="2026-02-10T15:16:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:16:56Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:01Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:17:01Z" level=debug msg="All connections have now ended." 1412s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 1412s time="2026-02-10T15:17:01Z" level=debug msg="Building new daemon" 1412s --- PASS: TestNew (0.00s) 1412s --- PASS: TestNew/Success (29.64s) 1412s --- PASS: TestNew/Error_when_WslPath_returns_error (26.43s) 1412s === CONT TestReconnection/Success_connecting_after_failing_to_connect 1412s time="2026-02-10T15:17:02Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:17:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42161" 1412s time="2026-02-10T15:17:35Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:17:35Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:17:35Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:17:35Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:17:35Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:17:38Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:17:38Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:17:38Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:17:38Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:17:38Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:17:38Z" 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_connect4098539240/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect4098539240/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1412s time="2026-02-10T15:17:38Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:17:38Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1412s time="2026-02-10T15:17:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39763" 1412s time="2026-02-10T15:17:39Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:17:39Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:17:49Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42161" 1412s time="2026-02-10T15:17:49Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:17:49Z" level=debug msg="Updated systemd status to \"Connected\"" 1412s time="2026-02-10T15:17:49Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:17:49Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s time="2026-02-10T15:17:55Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:39763" 1412s time="2026-02-10T15:17:55Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:17:55Z" level=debug msg="Updated systemd status to \"Connected\"" 1412s time="2026-02-10T15:17:55Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:17:55Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s daemon_test.go:465: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:465 1412s Error: Condition never satisfied 1412s Test: TestReconnection/Success_connecting_after_failing_to_connect 1412s Messages: Daemon never connected to agent's service 1412s time="2026-02-10T15:17:58Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:58Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:17:58Z" level=info msg="Stopping active requests." 1412s time="2026-02-10T15:17:58Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:58Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s === NAME TestReconnection/Success_connecting_after_previous_connection_dropped 1412s daemon_test.go:448: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:448 1412s Error: Condition never satisfied 1412s Test: TestReconnection/Success_connecting_after_previous_connection_dropped 1412s Messages: Daemon never connected to agent's service 1412s time="2026-02-10T15:17:59Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:59Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:17:59Z" level=info msg="Stopping active requests." 1412s time="2026-02-10T15:17:59Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:59Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:18:03Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:18:03Z" level=debug msg="All connections have now ended." 1412s === CONT TestRetryLogic/Without_retries 1412s === CONT TestServeAndQuit/Success_with_graceful_quit 1412s time="2026-02-10T15:18:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39575" 1412s time="2026-02-10T15:18:04Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:18:15Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:18:15Z" level=debug msg="All connections have now ended." 1412s --- FAIL: TestReconnection (0.00s) 1412s --- FAIL: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (54.41s) 1412s --- FAIL: TestReconnection/Success_connecting_after_previous_connection_dropped (62.09s) 1412s --- FAIL: TestReconnection/Success_connecting_after_failing_to_connect (72.91s) 1412s === CONT TestRetryLogic/Error_only_when_action_errors 1412s === CONT TestRetryLogic/When_max_attempts_are_exhausted 1412s daemon_test.go:398: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:398 1412s Error: Not equal: 1412s expected: true 1412s actual : false 1412s Test: TestRetryLogic/When_max_attempts_are_exhausted 1412s Messages: Mismatched expectation about calling the too many attempts callback 1412s === CONT TestRetryLogic/With_the_context_cancelled_while_waiting 1412s === CONT TestRetryLogic/With_the_context_pre-cancelled 1412s --- FAIL: TestRetryLogic (0.00s) 1412s --- PASS: TestRetryLogic/Without_retries (0.73s) 1412s --- PASS: TestRetryLogic/Error_only_when_action_errors (0.00s) 1412s --- FAIL: TestRetryLogic/When_max_attempts_are_exhausted (0.72s) 1412s --- PASS: TestRetryLogic/With_the_context_cancelled_while_waiting (0.05s) 1412s --- PASS: TestRetryLogic/With_the_context_pre-cancelled (0.00s) 1412s === CONT TestServeAndQuit/Success_with_double_quit 1412s time="2026-02-10T15:18:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34299" 1412s time="2026-02-10T15:18:16Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:18:42Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:18:42Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:18:43Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:18:43Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:18:43Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:19:00Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:19:00Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:19:00Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:19:00Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:19:00Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s === NAME TestServeAndQuit/Success_with_graceful_quit 1412s daemon_test.go:296: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:296 1412s Error: Condition never satisfied 1412s Test: TestServeAndQuit/Success_with_graceful_quit 1412s Messages: Systemd state should have been set to "STATUS=Connected" 1412s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 1412s time="2026-02-10T15:19:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42503" 1412s time="2026-02-10T15:19:03Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:19:04Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:19:17Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34299" 1412s time="2026-02-10T15:19:17Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:19:17Z" level=debug msg="Updated systemd status to \"Connected\"" 1412s time="2026-02-10T15:19:17Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s time="2026-02-10T15:19:17Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s === NAME TestServeAndQuit/Success_with_double_quit 1412s daemon_test.go:302: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:302 1412s Error: Condition never satisfied 1412s Test: TestServeAndQuit/Success_with_double_quit 1412s Messages: Daemon never connected to agent's service 1412s time="2026-02-10T15:19:27Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s === CONT TestServeAndQuit/Success_with_forceful_quit 1412s time="2026-02-10T15:19:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42073" 1412s time="2026-02-10T15:19:27Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:19:27Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:19:27Z" level=warning msg="context.Background rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:19:38Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:19:38Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:19:38Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:19:38Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:19:38Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s === CONT TestServe/No_connection_because_the_port_file_has_port_0 1412s time="2026-02-10T15:19:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32957" 1412s time="2026-02-10T15:19:39Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:19:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:20:12Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:20:12Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:20:12Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:20:12Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:20:12Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:20:16Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:20:16Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:20:16Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:20:16Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:0\": port cannot be zero" 1412s time="2026-02-10T15:20:16Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:20:17Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:20:17Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:20:17Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/No_connection_because_the_port_file_is_empty 1412s time="2026-02-10T15:20:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36285" 1412s time="2026-02-10T15:20:17Z" level=debug msg="Building new daemon" 1412s === NAME TestServeAndQuit/Success_with_forceful_quit 1412s daemon_test.go:296: 1412s Error Trace: /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:296 1412s Error: Condition never satisfied 1412s Test: TestServeAndQuit/Success_with_forceful_quit 1412s Messages: Systemd state should have been set to "STATUS=Connected" 1412s --- FAIL: TestServeAndQuit (0.00s) 1412s --- FAIL: TestServeAndQuit/Success_with_graceful_quit (59.19s) 1412s --- FAIL: TestServeAndQuit/Success_with_double_quit (71.11s) 1412s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (35.58s) 1412s --- FAIL: TestServeAndQuit/Success_with_forceful_quit (64.69s) 1412s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 1412s time="2026-02-10T15:20:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44309" 1412s time="2026-02-10T15:20:32Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:20:32Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:20:57Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:20:57Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:20:57Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:20:57Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"\": could not split address: missing port in address" 1412s time="2026-02-10T15:20:57Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:20:58Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:20:58Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:20:58Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/Success_with_a_broken_Landscape_config 1412s time="2026-02-10T15:20:58Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34393" 1412s time="2026-02-10T15:20:58Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:21:09Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:21:09Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:21:09Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:21:09Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:portyMcPortface\": could not parse port as an integer: strconv.Atoi: parsing \"portyMcPortface\": invalid syntax" 1412s time="2026-02-10T15:21:09Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:21:10Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:21:10Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:21:10Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/Success 1412s time="2026-02-10T15:21:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43787" 1412s time="2026-02-10T15:21:10Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:21:42Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:21:42Z" level=warning msg="Could not ensure valid Landscape configuration: could not ensure valid Landscape configuration: read /tmp/TestServeSuccess_with_a_broken_Landscape_config389060410/001/etc/landscape/client.conf: is a directory" 1412s time="2026-02-10T15:21:42Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:21:48Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:21:48Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:21:48Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:22:01Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:34393" 1412s time="2026-02-10T15:22:01Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:22:02Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:22:02Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s time="2026-02-10T15:22:10Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43787" 1412s time="2026-02-10T15:22:10Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:22:10Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:22:10Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s time="2026-02-10T15:22:21Z" level=debug msg="Server: sent preface messages to all streams" 1412s time="2026-02-10T15:22:21Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1412s time="2026-02-10T15:22:21Z" level=info msg="MockWindowsAgent: Connected ready" 1412s time="2026-02-10T15:22:21Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1412s time="2026-02-10T15:22:23Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:22:23Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:22:23Z" level=warning msg="Daemon: disconnected from Windows host" 1412s time="2026-02-10T15:22:23Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:22:23Z" level=debug msg="All connections have now ended." 1412s time="2026-02-10T15:22:23Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 1412s time="2026-02-10T15:22:23Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:34105" 1412s time="2026-02-10T15:22:23Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:22:23Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:22:23Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:22:33Z" level=debug msg="Server: sent preface messages to all streams" 1412s time="2026-02-10T15:22:33Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1412s time="2026-02-10T15:22:33Z" level=info msg="MockWindowsAgent: Connected ready" 1412s time="2026-02-10T15:22:33Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1412s time="2026-02-10T15:22:34Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:22:34Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:22:34Z" level=warning msg="Daemon: disconnected from Windows host" 1412s time="2026-02-10T15:22:34Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:22:34Z" level=debug msg="All connections have now ended." 1412s time="2026-02-10T15:22:34Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:22:34Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:22:34Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1412s === CONT TestServe/No_connection_because_there_is_no_server 1412s time="2026-02-10T15:22:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38365" 1412s time="2026-02-10T15:22:34Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:23:04Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:23:05Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:23:05Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:23:15Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:23:15Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:23:15Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:23:27Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:23:27Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:23:27Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 1412s time="2026-02-10T15:23:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37763" 1412s time="2026-02-10T15:23:27Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:23:32Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38365" 1412s time="2026-02-10T15:23:32Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:23:32Z" level=warning msg="Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:38365: connect: connection refused\"" 1412s time="2026-02-10T15:23:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:23:33Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:23:33Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:23:33Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/Error_because_the_notifier_returns_an_error 1412s time="2026-02-10T15:23:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:39199" 1412s time="2026-02-10T15:23:33Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:24:02Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:24:02Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:24:02Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:24:02Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not parse port from \"127.0.0.1:-5\": port cannot be negative" 1412s time="2026-02-10T15:24:02Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:24:03Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:24:03Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:24:03Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 1412s time="2026-02-10T15:24:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36423" 1412s time="2026-02-10T15:24:03Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:24:13Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 1412s time="2026-02-10T15:24:13Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:24:13Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:24:13Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/Success_with_systemd_notifier_returning_true 1412s time="2026-02-10T15:24:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38873" 1412s time="2026-02-10T15:24:13Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:24:43Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:24:43Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:24:43Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:24:43Z" level=warning msg="Daemon: could not connect to Windows Agent: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_the_port_file_does_not_exist2617086202/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2617086202/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 1412s time="2026-02-10T15:24:43Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:24:44Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:24:44Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:24:44Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 1412s time="2026-02-10T15:24:44Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38505" 1412s time="2026-02-10T15:24:44Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:24:51Z" level=debug msg="Ready state sent to systemd" 1412s time="2026-02-10T15:24:51Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:24:51Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:24:51Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:24:51Z" level=debug msg="Updated systemd status to \"Connecting\"" 1412s time="2026-02-10T15:25:10Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38873" 1412s time="2026-02-10T15:25:10Z" level=info msg="Daemon: completed connection to Windows Agent" 1412s time="2026-02-10T15:25:10Z" level=debug msg="Updated systemd status to \"Connected\"" 1412s time="2026-02-10T15:25:10Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 1412s time="2026-02-10T15:25:10Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 1412s time="2026-02-10T15:25:23Z" level=debug msg="Checking cloud-init status" 1412s time="2026-02-10T15:25:23Z" level=debug msg="No Landscape configuration detected: nothing to do" 1412s time="2026-02-10T15:25:23Z" level=info msg="Daemon: connecting to Windows Agent from PID 8454" 1412s time="2026-02-10T15:25:32Z" level=info msg="MockWindowsAgent: Connected ready" 1412s time="2026-02-10T15:25:32Z" level=debug msg="Server: sent preface messages to all streams" 1412s time="2026-02-10T15:25:32Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1412s time="2026-02-10T15:25:32Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1412s time="2026-02-10T15:25:34Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:25:34Z" level=warning msg="Daemon: disconnected from Windows host" 1412s time="2026-02-10T15:25:34Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:25:34Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 1412s time="2026-02-10T15:25:34Z" level=debug msg="Updated systemd status to \"Stopped\"" 1412s time="2026-02-10T15:25:34Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:25:34Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:25:34Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:25:34Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:25:34Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/Error_because_the_context_is_pre-cancelled 1412s time="2026-02-10T15:25:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36113" 1412s time="2026-02-10T15:25:34Z" level=debug msg="Building new daemon" 1412s time="2026-02-10T15:25:40Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:38505" 1412s time="2026-02-10T15:25:40Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_3840979815/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 1412s time="2026-02-10T15:25:40Z" level=info msg="Reconnecting to Windows host in 1 seconds" 1412s time="2026-02-10T15:25:41Z" level=info msg="Stopping daemon requested." 1412s time="2026-02-10T15:25:41Z" level=info msg="Waiting for active requests to close." 1412s time="2026-02-10T15:25:41Z" level=debug msg="All connections have now ended." 1412s === CONT TestServe/No_connection_because_there_are_no_certificates 1412s time="2026-02-10T15:25:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37301" 1412s time="2026-02-10T15:25:41Z" level=debug msg="Building new daemon" 1412s panic: test timed out after 10m0s 1412s running tests: 1412s TestServe/Error_because_the_context_is_pre-cancelled (33s) 1412s TestServe/No_connection_because_there_are_no_certificates (26s) 1412s 1412s goroutine 866 [running]: 1412s testing.(*M).startAlarm.func1() 1412s /usr/lib/go-1.25/src/testing/testing.go:2682 +0x444 1412s created by time.goFunc 1412s /usr/lib/go-1.25/src/time/sleep.go:215 +0x42 1412s 1412s goroutine 1 [chan receive, 8 minutes]: 1412s testing.tRunner.func1() 1412s /usr/lib/go-1.25/src/testing/testing.go:1891 +0x4f8 1412s testing.tRunner(0xc00008ea80, 0xc00019fc90) 1412s /usr/lib/go-1.25/src/testing/testing.go:1940 +0x182 1412s testing.runTests(0xc0000a2c60, {0xe084e0, 0x9, 0x9}, {0xc25af163b577f83c, 0x8bb408c5a9, 0xe16920}) 1412s /usr/lib/go-1.25/src/testing/testing.go:2475 +0x50a 1412s testing.(*M).Run(0xc0001b4f00) 1412s /usr/lib/go-1.25/src/testing/testing.go:2337 +0x6ec 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestMain(...) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:24 1412s main.main() 1412s _testmain.go:65 +0xfc 1412s 1412s goroutine 301 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1.1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:285 +0x70 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1 in goroutine 13 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:284 +0x356 1412s 1412s goroutine 21 [chan receive, 5 minutes]: 1412s testing.tRunner.func1() 1412s /usr/lib/go-1.25/src/testing/testing.go:1891 +0x4f8 1412s testing.tRunner(0xc00008efc0, 0x8e5370) 1412s /usr/lib/go-1.25/src/testing/testing.go:1940 +0x182 1412s created by testing.(*T).Run in goroutine 1 1412s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1412s 1412s goroutine 409 [chan send, 5 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1.1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:285 +0x70 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1 in goroutine 14 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:284 +0x356 1412s 1412s goroutine 549 [chan send, 3 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 536 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 748 [select]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c72b0, {0x9acad8, 0xc00029a410}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 734 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 550 [chan send, 3 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 537 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 852 [select]: 1412s os/exec.(*Cmd).watchCtx(0xc000420780, 0xc0003b37a0) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:789 +0xa8 1412s created by os/exec.(*Cmd).Start in goroutine 51 1412s /usr/lib/go-1.25/src/os/exec/exec.go:775 +0xa2a 1412s 1412s goroutine 386 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 371 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 71 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc000030e50, {0x9acad8, 0xc000020a00}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 45 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 243 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c76c0, {0x9acad8, 0xc0003ecaf0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 149 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 344 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d720, {0x9acad8, 0xc000400cd0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 330 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 93 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 45 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 196 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c6920, {0x9acad8, 0xc000021ef0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 142 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 244 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c76f0, {0x9acad8, 0xc0003ecb40}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 149 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 94 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 45 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 851 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff52c89a00, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc0001bb700, 0x72, 0x1) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Read(0xc0001bb6e0, {0xc0002a4800, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1412s os.(*File).read(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:29 1412s os.(*File).Read(0xc000050b18, {0xc0002a4800, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1412s bytes.(*Buffer).ReadFrom(0xc00049f3b0, {0x9a65a0, 0xc000050b48}) 1412s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1412s io.copyBuffer({0x9a6bc0, 0xc00049f3b0}, {0x9a65a0, 0xc000050b48}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os.genericWriteTo(0xc000050b18, {0x9a6bc0, 0xc00049f3b0}) 1412s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1412s os.(*File).WriteTo(0xc000050b18, {0x9a6bc0, 0xc00049f3b0}) 1412s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1412s io.copyBuffer({0x9a6bc0, 0xc00049f3b0}, {0x9a6620, 0xc000050b18}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os/exec.(*Cmd).writerDescriptor.func1() 1412s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1412s os/exec.(*Cmd).Start.func2(0xc000361140) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1412s created by os/exec.(*Cmd).Start in goroutine 51 1412s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1412s 1412s goroutine 848 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff52c89200, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc0001bb520, 0x72, 0x1) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Read(0xc0001bb500, {0xc000350200, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1412s os.(*File).read(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:29 1412s os.(*File).Read(0xc000050ad0, {0xc000350200, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1412s bytes.(*Buffer).ReadFrom(0xc00049ef60, {0x9a65a0, 0xc00009e558}) 1412s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1412s io.copyBuffer({0x9a6bc0, 0xc00049ef60}, {0x9a65a0, 0xc00009e558}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os.genericWriteTo(0xc000050ad0, {0x9a6bc0, 0xc00049ef60}) 1412s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1412s os.(*File).WriteTo(0xc000050ad0, {0x9a6bc0, 0xc00049ef60}) 1412s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1412s io.copyBuffer({0x9a6bc0, 0xc00049ef60}, {0x9a6620, 0xc000050ad0}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os/exec.(*Cmd).writerDescriptor.func1() 1412s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1412s os/exec.(*Cmd).Start.func2(0xc000361040) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1412s created by os/exec.(*Cmd).Start in goroutine 53 1412s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1412s 1412s goroutine 561 [select, 3 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0004f2ca0, {0x9acad8, 0xc0000b6aa0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 492 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 509 [select, 5 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d810, {0x9acad8, 0xc0003ec870}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 471 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 578 [select, 3 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0004f2cd0, {0x9acad8, 0xc0000b6af0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 492 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 51 [syscall]: 1412s syscall.Syscall6(0x119, 0x3, 0x11, 0xc000035850, 0x4, 0xc00029fe60, 0x0) 1412s /usr/lib/go-1.25/src/syscall/syscall_linux.go:96 +0x18 1412s internal/syscall/unix.Waitid(0x3, 0x11, 0xc000035850, 0x4, 0xc00029fe60) 1412s /usr/lib/go-1.25/src/internal/syscall/unix/waitid_linux.go:18 +0x6c 1412s os.(*Process).pidfdWait.func1(...) 1412s /usr/lib/go-1.25/src/os/pidfd_linux.go:109 1412s os.ignoringEINTR(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:256 1412s os.(*Process).pidfdWait(0xc0004a0ec0) 1412s /usr/lib/go-1.25/src/os/pidfd_linux.go:108 +0x178 1412s os.(*Process).wait(0xc0004a0ec0) 1412s /usr/lib/go-1.25/src/os/exec_unix.go:25 +0x3c 1412s os.(*Process).Wait(...) 1412s /usr/lib/go-1.25/src/os/exec.go:340 1412s os/exec.(*Cmd).Wait(0xc000420780) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:922 +0x4a 1412s os/exec.(*Cmd).Run(0xc000420780) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:626 +0x66 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc000420780) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:226 +0x1b6 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).UserProfileDir(0xc0001e1cb0, {0x9acad8, 0xc000400500}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:198 +0x280 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon.New({0x9acad8, 0xc000400500}, 0xc0001e1cb0, {0xc000035eb0, 0x1, 0x1}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:86 +0x10e 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc000229500) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:171 +0xc02 1412s testing.tRunner(0xc000229500, 0xc000024198) 1412s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1412s created by testing.(*T).Run in goroutine 21 1412s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1412s 1412s goroutine 843 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff51af1800, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc0004e0ba0, 0x72, 0x0) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Accept(0xc0004e0b80) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:613 +0x2d8 1412s net.(*netFD).accept(0xc0004e0b80) 1412s /usr/lib/go-1.25/src/net/fd_unix.go:161 +0x3c 1412s net.(*TCPListener).accept(0xc000337880) 1412s /usr/lib/go-1.25/src/net/tcpsock_posix.go:159 +0x36 1412s net.(*TCPListener).Accept(0xc000337880) 1412s /usr/lib/go-1.25/src/net/tcpsock.go:380 +0x46 1412s google.golang.org/grpc.(*Server).Serve(0xc00022d600, {0x9ab6f0, 0xc000337880}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/server.go:890 +0x4d4 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:81 +0x19a 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent in goroutine 51 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:75 +0x648 1412s 1412s goroutine 53 [syscall]: 1412s syscall.Syscall6(0x119, 0x3, 0x12, 0xc00044d850, 0x4, 0xc00029fdd0, 0x0) 1412s /usr/lib/go-1.25/src/syscall/syscall_linux.go:96 +0x18 1412s internal/syscall/unix.Waitid(0x3, 0x12, 0xc00044d850, 0x4, 0xc00029fdd0) 1412s /usr/lib/go-1.25/src/internal/syscall/unix/waitid_linux.go:18 +0x6c 1412s os.(*Process).pidfdWait.func1(...) 1412s /usr/lib/go-1.25/src/os/pidfd_linux.go:109 1412s os.ignoringEINTR(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:256 1412s os.(*Process).pidfdWait(0xc0004a0e40) 1412s /usr/lib/go-1.25/src/os/pidfd_linux.go:108 +0x178 1412s os.(*Process).wait(0xc0004a0e40) 1412s /usr/lib/go-1.25/src/os/exec_unix.go:25 +0x3c 1412s os.(*Process).Wait(...) 1412s /usr/lib/go-1.25/src/os/exec.go:340 1412s os/exec.(*Cmd).Wait(0xc000420600) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:922 +0x4a 1412s os/exec.(*Cmd).Run(0xc000420600) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:626 +0x66 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc000420600) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:226 +0x1b6 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).UserProfileDir(0xc0003e3020, {0x9acad8, 0xc0002499a0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:198 +0x280 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon.New({0x9acad8, 0xc0002499a0}, 0xc0003e3020, {0xc00044deb0, 0x1, 0x1}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon.go:86 +0x10e 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServe.func1(0xc000229c00) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:171 +0xc02 1412s testing.tRunner(0xc000229c00, 0xc0000241c8) 1412s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1412s created by testing.(*T).Run in goroutine 21 1412s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1412s 1412s goroutine 837 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff52c89e00, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc000239d20, 0x72, 0x0) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Accept(0xc000239d00) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:613 +0x2d8 1412s net.(*netFD).accept(0xc000239d00) 1412s /usr/lib/go-1.25/src/net/fd_unix.go:161 +0x3c 1412s net.(*TCPListener).accept(0xc000336140) 1412s /usr/lib/go-1.25/src/net/tcpsock_posix.go:159 +0x36 1412s net.(*TCPListener).Accept(0xc000336140) 1412s /usr/lib/go-1.25/src/net/tcpsock.go:380 +0x46 1412s google.golang.org/grpc.(*Server).Serve(0xc00022d400, {0x9ab6f0, 0xc000336140}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/server.go:890 +0x4d4 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:81 +0x19a 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils.NewMockWindowsAgent in goroutine 53 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/testutils/mock_agent.go:75 +0x648 1412s 1412s goroutine 750 [select]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c7310, {0x9acad8, 0xc00029a4b0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 734 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 665 [select]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0000a7640, {0x9acad8, 0xc0003ed2c0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 655 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 195 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c68f0, {0x9acad8, 0xc000021ea0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 142 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 371 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 330 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 72 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc000030e80, {0x9acad8, 0xc000020a50}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 45 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 73 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc000030eb0, {0x9acad8, 0xc000020aa0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 45 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 193 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 142 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 818 [select]: 1412s google.golang.org/grpc.(*addrConn).resetTransportAndUnlock(0xc00032e308) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/clientconn.go:1297 +0x4c6 1412s google.golang.org/grpc.(*addrConn).connect(0xc00032e308) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/clientconn.go:933 +0x1e4 1412s created by google.golang.org/grpc.(*acBalancerWrapper).Connect in goroutine 666 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/balancer_wrapper.go:354 +0x96 1412s 1412s goroutine 242 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c7690, {0x9acad8, 0xc0003ecaa0}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 149 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 277 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 260 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 276 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 259 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 210 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 142 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 102 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 93 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 103 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 94 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 194 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c68c0, {0x9acad8, 0xc000021e50}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 142 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 511 [select, 3 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d870, {0x9acad8, 0xc0003eca50}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 471 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 850 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff52c89600, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc0001bb640, 0x72, 0x1) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Read(0xc0001bb620, {0xc0002a4600, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1412s os.(*File).read(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:29 1412s os.(*File).Read(0xc000050b00, {0xc0002a4600, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1412s bytes.(*Buffer).ReadFrom(0xc00049f2f0, {0x9a65a0, 0xc000050b40}) 1412s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1412s io.copyBuffer({0x9a6bc0, 0xc00049f2f0}, {0x9a65a0, 0xc000050b40}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os.genericWriteTo(0xc000050b00, {0x9a6bc0, 0xc00049f2f0}) 1412s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1412s os.(*File).WriteTo(0xc000050b00, {0x9a6bc0, 0xc00049f2f0}) 1412s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1412s io.copyBuffer({0x9a6bc0, 0xc00049f2f0}, {0x9a6620, 0xc000050b00}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os/exec.(*Cmd).writerDescriptor.func1() 1412s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1412s os/exec.(*Cmd).Start.func2(0xc000361100) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1412s created by os/exec.(*Cmd).Start in goroutine 51 1412s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1412s 1412s goroutine 849 [select]: 1412s os/exec.(*Cmd).watchCtx(0xc000420600, 0xc0003b2fc0) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:789 +0xa8 1412s created by os/exec.(*Cmd).Start in goroutine 53 1412s /usr/lib/go-1.25/src/os/exec/exec.go:775 +0xa2a 1412s 1412s goroutine 598 [chan send, 3 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 593 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 847 [IO wait]: 1412s internal/poll.runtime_pollWait(0x3ff51af1e00, 0x72) 1412s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1412s internal/poll.(*pollDesc).wait(0xc0001bb460, 0x72, 0x1) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1412s internal/poll.(*pollDesc).waitRead(...) 1412s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1412s internal/poll.(*FD).Read(0xc0001bb440, {0xc000350000, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1412s os.(*File).read(...) 1412s /usr/lib/go-1.25/src/os/file_posix.go:29 1412s os.(*File).Read(0xc000050ab8, {0xc000350000, 0x200, 0x200}) 1412s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1412s bytes.(*Buffer).ReadFrom(0xc00049ef30, {0x9a65a0, 0xc00009e550}) 1412s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1412s io.copyBuffer({0x9a6bc0, 0xc00049ef30}, {0x9a65a0, 0xc00009e550}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os.genericWriteTo(0xc000050ab8, {0x9a6bc0, 0xc00049ef30}) 1412s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1412s os.(*File).WriteTo(0xc000050ab8, {0x9a6bc0, 0xc00049ef30}) 1412s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1412s io.copyBuffer({0x9a6bc0, 0xc00049ef30}, {0x9a6620, 0xc000050ab8}, {0x0, 0x0, 0x0}) 1412s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1412s io.Copy(...) 1412s /usr/lib/go-1.25/src/io/io.go:388 1412s os/exec.(*Cmd).writerDescriptor.func1() 1412s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1412s os/exec.(*Cmd).Start.func2(0xc000361000) 1412s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1412s created by os/exec.(*Cmd).Start in goroutine 53 1412s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1412s 1412s goroutine 599 [chan send, 3 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 594 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 208 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 193 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 209 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 210 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 666 [select]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0000a7680, {0x9acad8, 0xc0003ed310}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 655 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 346 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d780, {0x9acad8, 0xc000400d70}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 330 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 510 [select, 3 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d840, {0x9acad8, 0xc0003eca00}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 471 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 345 [select, 8 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc00034d750, {0x9acad8, 0xc000400d20}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 330 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 330 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1.1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:285 +0x70 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon_test.TestServeAndQuit.func1 in goroutine 15 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/daemon/daemon_test.go:284 +0x356 1412s 1412s goroutine 560 [select, 5 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0004f2c70, {0x9acad8, 0xc0000b6a50}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 492 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 372 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 330 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 664 [select, 3 minutes]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0000a75a0, {0x9acad8, 0xc0003ed270}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 655 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 259 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 149 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 260 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve.func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:121 +0xb8 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.(*Server).Serve in goroutine 149 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:119 +0x418 1412s 1412s goroutine 779 [chan send]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 788 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 353 [chan send, 8 minutes]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 372 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s 1412s goroutine 749 [select]: 1412s google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0xc0003c72e0, {0x9acad8, 0xc00029a460}) 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:88 +0x104 1412s created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 734 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/vendor/google.golang.org/grpc/internal/grpcsync/callback_serializer.go:52 +0x194 1412s 1412s goroutine 780 [chan send]: 1412s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...].func1() 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:244 +0x8e 1412s created by github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams.receiveWithContext[...] in goroutine 789 1412s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/streams/server.go:241 +0x102 1412s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 600.391s 1412s === RUN TestConnect 1412s === PAUSE TestConnect 1412s === RUN TestSendAndRecv 1412s --- PASS: TestSendAndRecv (0.88s) 1412s === RUN TestServe 1412s === PAUSE TestServe 1412s === RUN TestStop 1412s === PAUSE TestStop 1412s === RUN TestWithProMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithProMock (0.00s) 1412s === RUN TestWithWslPathMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithWslPathMock (0.00s) 1412s === RUN TestWithWslInfoMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithWslInfoMock (0.00s) 1412s === RUN TestWithCmdExeMock 1412s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1412s --- SKIP: TestWithCmdExeMock (0.00s) 1412s === CONT TestConnect 1412s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 1412s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 1412s === RUN TestConnect/Success 1412s === PAUSE TestConnect/Success 1412s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 1412s === CONT TestStop 1412s time="2026-02-10T15:16:23Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42625" 1412s time="2026-02-10T15:16:41Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1412s time="2026-02-10T15:16:41Z" level=info msg="MockWindowsAgent: Connected ready" 1412s time="2026-02-10T15:16:41Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1412s === CONT TestConnect/Success 1412s === CONT TestServe 1412s --- PASS: TestConnect (0.00s) 1412s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.23s) 1412s --- PASS: TestConnect/Success (0.46s) 1412s time="2026-02-10T15:16:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:36249" 1412s time="2026-02-10T15:16:52Z" level=warning msg="Streamserver: could not gather info after command completion: could not obtain pro status: pro status: /usr/bin/bash: error: context canceled.\n Stdout: \n Stderr: " 1412s time="2026-02-10T15:16:52Z" level=warning msg="Streamserver: could not stream back info after command completion: EOF" 1412s time="2026-02-10T15:16:52Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1412s --- PASS: TestStop (29.23s) 1412s time="2026-02-10T15:16:52Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:16:52Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:16:58Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 1412s time="2026-02-10T15:16:58Z" level=info msg="MockWindowsAgent: Connected ready" 1412s time="2026-02-10T15:16:58Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 1412s time="2026-02-10T15:17:39Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:39Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 1412s time="2026-02-10T15:17:39Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 1412s --- PASS: TestServe (55.67s) 1412s PASS 1412s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 77.448s 1520s === RUN TestNew 1520s === PAUSE TestNew 1520s === RUN TestInfo 1520s === PAUSE TestInfo 1520s === RUN TestWslDistroName 1520s === PAUSE TestWslDistroName 1520s === RUN TestUserProfileDir 1520s === PAUSE TestUserProfileDir 1520s === RUN TestProStatus 1520s === PAUSE TestProStatus 1520s === RUN TestProAttach 1520s === PAUSE TestProAttach 1520s === RUN TestProDetach 1520s === PAUSE TestProDetach 1520s === RUN TestLandscapeEnable 1520s === PAUSE TestLandscapeEnable 1520s === RUN TestWindowsHostAddress 1520s === PAUSE TestWindowsHostAddress 1520s === RUN TestLandscapeDisable 1520s === PAUSE TestLandscapeDisable 1520s === RUN TestEnsureValidLandscapeConfig 1520s === PAUSE TestEnsureValidLandscapeConfig 1520s === RUN TestRealBackend 1520s === PAUSE TestRealBackend 1520s === RUN TestWithProMock 1520s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1520s --- SKIP: TestWithProMock (0.00s) 1520s === RUN TestWithLandscapeConfigMock 1520s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1520s --- SKIP: TestWithLandscapeConfigMock (0.00s) 1520s === RUN TestWithWslPathMock 1520s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1520s --- SKIP: TestWithWslPathMock (0.00s) 1520s === RUN TestWithWslInfoMock 1520s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1520s --- SKIP: TestWithWslInfoMock (0.00s) 1520s === RUN TestWithCmdExeMock 1520s mock_executables.go:650: Skipped because it is not a real test, but rather a mocked executable 1520s --- SKIP: TestWithCmdExeMock (0.00s) 1520s === CONT TestNew 1520s === RUN TestNew/Return_a_new_system 1520s === PAUSE TestNew/Return_a_new_system 1520s === RUN TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 1520s === PAUSE TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 1520s === CONT TestNew/Return_a_new_system 1520s === CONT TestProDetach 1520s === RUN TestProDetach/success_on_unattached_distro 1520s === PAUSE TestProDetach/success_on_unattached_distro 1520s === RUN TestProDetach/success_on_attached_distro 1520s === PAUSE TestProDetach/success_on_attached_distro 1520s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1520s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1520s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 1520s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 1520s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1520s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1520s === CONT TestRealBackend 1520s --- PASS: TestRealBackend (0.00s) 1520s === CONT TestEnsureValidLandscapeConfig 1520s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 1520s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 1520s === RUN TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s === PAUSE TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s === RUN TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 1520s === PAUSE TestEnsureValidLandscapeConfig/Do_not_modify_other_sections_and_keys 1520s === CONT TestLandscapeDisable 1520s === RUN TestLandscapeDisable/Success 1520s === PAUSE TestLandscapeDisable/Success 1520s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1520s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1520s === CONT TestWindowsHostAddress 1520s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1520s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1520s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1520s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1520s === RUN TestWindowsHostAddress/Without_NAT 1520s === PAUSE TestWindowsHostAddress/Without_NAT 1520s === RUN TestWindowsHostAddress/With_NAT 1520s === PAUSE TestWindowsHostAddress/With_NAT 1520s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1520s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1520s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1520s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1520s === CONT TestLandscapeEnable 1520s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path 1520s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path 1520s === RUN TestLandscapeEnable/Rerun_landscape_even_without_modifications 1520s === PAUSE TestLandscapeEnable/Rerun_landscape_even_without_modifications 1520s === RUN TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 1520s === PAUSE TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 1520s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1520s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1520s === RUN TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 1520s === PAUSE TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 1520s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1520s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1520s === RUN TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 1520s === PAUSE TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 1520s === RUN TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s === PAUSE TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s === RUN TestLandscapeEnable/Refresh_computer_title_if_changed 1520s === PAUSE TestLandscapeEnable/Refresh_computer_title_if_changed 1520s === RUN TestLandscapeEnable/Regular_with_additional_keys 1520s === PAUSE TestLandscapeEnable/Regular_with_additional_keys 1520s === RUN TestLandscapeEnable/Do_not_modify_other_sections_and_keys 1520s === PAUSE TestLandscapeEnable/Do_not_modify_other_sections_and_keys 1520s === RUN TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 1520s === PAUSE TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 1520s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 1520s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 1520s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1520s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1520s === RUN TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 1520s === PAUSE TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 1520s === RUN TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 1520s === PAUSE TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 1520s === CONT TestUserProfileDir 1520s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1520s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1520s === RUN TestUserProfileDir/Error_on_cmd.exe_error 1520s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 1520s === RUN TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 1520s === PAUSE TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 1520s === RUN TestUserProfileDir/Error_on_wslpath_error 1520s === PAUSE TestUserProfileDir/Error_on_wslpath_error 1520s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 1520s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 1520s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1520s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1520s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1520s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1520s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1520s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1520s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1520s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1520s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1520s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1520s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1520s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1520s === CONT TestProAttach 1520s === RUN TestProAttach/success 1520s === PAUSE TestProAttach/success 1520s === RUN TestProAttach/error_on_'pro_attach'_error 1520s === PAUSE TestProAttach/error_on_'pro_attach'_error 1520s === CONT TestProStatus 1520s === RUN TestProStatus/success_on_attached_distro 1520s === PAUSE TestProStatus/success_on_attached_distro 1520s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 1520s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 1520s === RUN TestProStatus/error_on_'pro_attach'_error 1520s === PAUSE TestProStatus/error_on_'pro_attach'_error 1520s === RUN TestProStatus/success_on_unattached_distro 1520s === PAUSE TestProStatus/success_on_unattached_distro 1520s === CONT TestInfo 1520s === RUN TestInfo/Error_when_WslDistroName_fails 1520s === PAUSE TestInfo/Error_when_WslDistroName_fails 1520s === RUN TestInfo/Error_when_pro_status_command_fails 1520s === PAUSE TestInfo/Error_when_pro_status_command_fails 1520s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 1520s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 1520s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 1520s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 1520s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1520s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1520s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 1520s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 1520s === RUN TestInfo/Success 1520s === PAUSE TestInfo/Success 1520s === CONT TestWslDistroName 1520s === RUN TestWslDistroName/Success_using_wslpath 1520s === PAUSE TestWslDistroName/Success_using_wslpath 1520s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1520s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1520s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1520s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1520s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1520s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1520s === CONT TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) 1520s --- PASS: TestNew (0.00s) 1520s --- PASS: TestNew/Return_a_new_system (0.00s) 1520s --- PASS: TestNew/Ignore_errors_when_the_Landscape_config_validation_failed_(only_warnings) (0.00s) 1520s === CONT TestProDetach/success_on_unattached_distro 1520s === RUN TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 1520s === PAUSE TestEnsureValidLandscapeConfig/No_Landscape_configuration_means_no_landscape_command_nor_config_created 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_cannot_be_parsed 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_failing_to_override_the_SSL_certficate_path 1520s === RUN TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 1520s === PAUSE TestEnsureValidLandscapeConfig/Appends_any_required_fields_-_no_ssl 1520s === RUN TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 1520s === PAUSE TestEnsureValidLandscapeConfig/Refresh_computer_title_if_changed 1520s === RUN TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 1520s === PAUSE TestEnsureValidLandscapeConfig/Do_not_transform_Windows_SSL_certificate_empty_path 1520s === RUN TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 1520s === PAUSE TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 1520s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 1520s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_due_whitespace_changes 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_new_config_do_not_have_client_section 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape-config_command_fails 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_can_not_get_WSL_Distro_name 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 1520s === RUN TestEnsureValidLandscapeConfig/Regular_with_additional_keys 1520s === PAUSE TestEnsureValidLandscapeConfig/Regular_with_additional_keys 1520s === RUN TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 1520s === PAUSE TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 1520s === RUN TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 1520s === PAUSE TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 1520s === CONT TestLandscapeDisable/Success 1520s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 1520s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 1520s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path 1520s --- PASS: TestLandscapeDisable (0.00s) 1520s --- PASS: TestLandscapeDisable/Success (20.09s) 1520s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (16.06s) 1520s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 1520s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 1520s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path 1520s === CONT TestWindowsHostAddress/With_NAT 1520s === CONT TestWindowsHostAddress/Without_NAT 1520s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 1520s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 1520s system_test.go:245: Removing default proc/mounts 1520s === CONT TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created 1520s --- PASS: TestWindowsHostAddress (0.00s) 1520s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (16.74s) 1520s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (21.79s) 1520s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (18.08s) 1520s --- PASS: TestWindowsHostAddress/Without_NAT (17.35s) 1520s --- PASS: TestWindowsHostAddress/With_NAT (20.53s) 1520s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (22.18s) 1520s === CONT TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section 1520s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 1520s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed 1520s === CONT TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 1520s === CONT TestLandscapeEnable/Do_not_modify_other_sections_and_keys 1520s === NAME TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/reformat_landscape_config_to_proper_ini 1520s === CONT TestLandscapeEnable/Regular_with_additional_keys 1520s === NAME TestLandscapeEnable/Do_not_modify_other_sections_and_keys 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/do_not_modify_other_sections_and_keys 1520s === CONT TestLandscapeEnable/Refresh_computer_title_if_changed 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/refresh_computer_title_if_changed 1520s === CONT TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s === NAME TestLandscapeEnable/Regular_with_additional_keys 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/regular_with_additional_keys 1520s === CONT TestLandscapeEnable/Appends_any_required_fields_-_no_ssl 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/appends_any_required_fields_-_no_ssl 1520s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 1520s === CONT TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name 1520s === NAME TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/transform_windows_ssl_certificate_path_with_forward_slash 1520s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 1520s === CONT TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed 1520s === CONT TestLandscapeEnable/Rerun_landscape_even_without_modifications 1520s === CONT TestProAttach/success 1520s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 1520s === NAME TestLandscapeEnable/Rerun_landscape_even_without_modifications 1520s system_test.go:526: testdata/TestLandscapeEnable/golden/rerun_landscape_even_without_modifications 1520s --- PASS: TestLandscapeEnable (0.00s) 1520s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path (38.49s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_new_config_do_not_have_client_section (0.00s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_config_directory_cannot_be_created (21.07s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_renamed (19.71s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (40.97s) 1520s --- PASS: TestLandscapeEnable/Reformat_Landscape_config_to_proper_ini (36.04s) 1520s --- PASS: TestLandscapeEnable/Do_not_modify_other_sections_and_keys (32.23s) 1520s --- PASS: TestLandscapeEnable/Refresh_computer_title_if_changed (19.15s) 1520s --- PASS: TestLandscapeEnable/Regular_with_additional_keys (37.40s) 1520s --- PASS: TestLandscapeEnable/Appends_any_required_fields_-_no_ssl (17.69s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (20.32s) 1520s --- PASS: TestLandscapeEnable/Transform_Windows_SSL_certificate_path_with_forward_slash (40.03s) 1520s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (16.13s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_new_config_cannot_be_parsed (0.00s) 1520s --- PASS: TestLandscapeEnable/Error_when_the_can_not_get_WSL_Distro_name (18.80s) 1520s --- PASS: TestLandscapeEnable/Rerun_landscape_even_without_modifications (37.96s) 1520s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 1520s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 1520s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 1520s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 1520s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 1520s system_test.go:245: Removing default proc/mounts 1520s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 1520s === CONT TestUserProfileDir/Error_on_wslpath_error 1520s === CONT TestUserProfileDir/Error_when_UserProfile_env_var_is_empty 1520s === CONT TestUserProfileDir/Error_on_cmd.exe_error 1520s === CONT TestProStatus/success_on_attached_distro 1520s --- PASS: TestUserProfileDir (0.00s) 1520s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 1520s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (44.00s) 1520s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (40.51s) 1520s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (38.77s) 1520s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 1520s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 1520s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (37.79s) 1520s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (39.20s) 1520s --- PASS: TestUserProfileDir/Error_on_wslpath_error (32.93s) 1520s --- PASS: TestUserProfileDir/Error_when_UserProfile_env_var_is_empty (22.68s) 1520s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (19.48s) 1520s === CONT TestProAttach/error_on_'pro_attach'_error 1520s === CONT TestInfo/Error_when_WslDistroName_fails 1520s --- PASS: TestProAttach (0.00s) 1520s --- PASS: TestProAttach/success (17.68s) 1520s --- PASS: TestProAttach/error_on_'pro_attach'_error (20.33s) 1520s === CONT TestProStatus/success_on_unattached_distro 1520s === CONT TestProStatus/error_on_'pro_attach'_error 1520s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 1520s === CONT TestWslDistroName/Success_using_wslpath 1520s --- PASS: TestProStatus (0.00s) 1520s --- PASS: TestProStatus/success_on_attached_distro (16.33s) 1520s --- PASS: TestProStatus/success_on_unattached_distro (17.37s) 1520s --- PASS: TestProStatus/error_on_'pro_attach'_error (17.44s) 1520s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (19.66s) 1520s === CONT TestInfo/Success 1520s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 1520s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 1520s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 1520s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 1520s === CONT TestInfo/Error_when_pro_status_command_fails 1520s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 1520s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 1520s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 1520s --- PASS: TestInfo (0.00s) 1520s --- PASS: TestInfo/Error_when_WslDistroName_fails (19.32s) 1520s --- PASS: TestInfo/Success (15.74s) 1520s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (15.07s) 1520s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (14.16s) 1520s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (8.51s) 1520s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (10.19s) 1520s --- PASS: TestInfo/Error_when_pro_status_command_fails (11.17s) 1520s === CONT TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 1520s --- PASS: TestWslDistroName (0.00s) 1520s --- PASS: TestWslDistroName/Success_using_wslpath (20.66s) 1520s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 1520s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (11.39s) 1520s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (10.82s) 1520s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 1520s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 1520s === NAME TestEnsureValidLandscapeConfig/Transform_Windows_SSL_certificate_path 1520s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/transform_windows_ssl_certificate_path 1520s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 1520s === CONT TestProDetach/success_on_attached_distro 1520s === CONT TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 1520s === CONT TestEnsureValidLandscapeConfig/Error_when_the_config_file_cannot_be_read 1520s --- PASS: TestProDetach (0.05s) 1520s --- PASS: TestProDetach/success_on_unattached_distro (20.16s) 1520s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (11.15s) 1520s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (9.32s) 1520s --- PASS: TestProDetach/success_on_attached_distro (9.54s) 1520s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (11.47s) 1520s === CONT TestEnsureValidLandscapeConfig/Do_not_rerun_landscape_without_modifications 1520s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/do_not_rerun_landscape_without_modifications 1520s === CONT TestEnsureValidLandscapeConfig/Regular_with_additional_keys 1520s === NAME TestEnsureValidLandscapeConfig/Reformat_Landscape_config_to_proper_ini 1520s system_test.go:768: testdata/TestEnsureValidLandscapeConfig/golden/reformat_landscape_config_to_proper_ini 1520s === CONT TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist 1520s panic: test timed out after 10m0s 1520s running tests: 1520s TestEnsureValidLandscapeConfig/Error_when_the_landscape_user_does_not_exist (0s) 1520s TestEnsureValidLandscapeConfig/Regular_with_additional_keys (8s) 1520s 1520s goroutine 251 [running]: 1520s testing.(*M).startAlarm.func1() 1520s /usr/lib/go-1.25/src/testing/testing.go:2682 +0x444 1520s created by time.goFunc 1520s /usr/lib/go-1.25/src/time/sleep.go:215 +0x42 1520s 1520s goroutine 1 [chan receive]: 1520s testing.tRunner.func1() 1520s /usr/lib/go-1.25/src/testing/testing.go:1891 +0x4f8 1520s testing.tRunner(0xc000166380, 0xc000163ca0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1940 +0x182 1520s testing.runTests(0xc00000ec78, {0xca6520, 0x11, 0x11}, {0xc25af17ee72015e6, 0x8bb2f2541a, 0xcb3f80}) 1520s /usr/lib/go-1.25/src/testing/testing.go:2475 +0x50a 1520s testing.(*M).Run(0xc00017cd20) 1520s /usr/lib/go-1.25/src/testing/testing.go:2337 +0x6ec 1520s main.main() 1520s _testmain.go:79 +0xd8 1520s 1520s goroutine 250 [IO wait]: 1520s internal/poll.runtime_pollWait(0x3ffbcf75c00, 0x72) 1520s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1520s internal/poll.(*pollDesc).wait(0xc000492620, 0x72, 0x1) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1520s internal/poll.(*pollDesc).waitRead(...) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1520s internal/poll.(*FD).Read(0xc000492600, {0xc0001a9a00, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1520s os.(*File).read(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:29 1520s os.(*File).Read(0xc000220100, {0xc0001a9a00, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1520s bytes.(*Buffer).ReadFrom(0xc0001a2630, {0x8ae820, 0xc00046e408}) 1520s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2630}, {0x8ae820, 0xc00046e408}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os.genericWriteTo(0xc000220100, {0x8ae9e0, 0xc0001a2630}) 1520s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1520s os.(*File).WriteTo(0xc000220100, {0x8ae9e0, 0xc0001a2630}) 1520s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2630}, {0x8ae8a0, 0xc000220100}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os/exec.(*Cmd).writerDescriptor.func1() 1520s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1520s os/exec.(*Cmd).Start.func2(0xc0003da300) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1520s created by os/exec.(*Cmd).Start in goroutine 101 1520s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1520s 1520s goroutine 18 [chan receive]: 1520s testing.tRunner.func1() 1520s /usr/lib/go-1.25/src/testing/testing.go:1891 +0x4f8 1520s testing.tRunner(0xc0001808c0, 0x802050) 1520s /usr/lib/go-1.25/src/testing/testing.go:1940 +0x182 1520s created by testing.(*T).Run in goroutine 1 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 249 [IO wait]: 1520s internal/poll.runtime_pollWait(0x3ffbcf76400, 0x72) 1520s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1520s internal/poll.(*pollDesc).wait(0xc000492500, 0x72, 0x1) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1520s internal/poll.(*pollDesc).waitRead(...) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1520s internal/poll.(*FD).Read(0xc0004924e0, {0xc0001a9800, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1520s os.(*File).read(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:29 1520s os.(*File).Read(0xc0002200e0, {0xc0001a9800, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1520s bytes.(*Buffer).ReadFrom(0xc0001a2600, {0x8ae820, 0xc00046e400}) 1520s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2600}, {0x8ae820, 0xc00046e400}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os.genericWriteTo(0xc0002200e0, {0x8ae9e0, 0xc0001a2600}) 1520s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1520s os.(*File).WriteTo(0xc0002200e0, {0x8ae9e0, 0xc0001a2600}) 1520s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2600}, {0x8ae8a0, 0xc0002200e0}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os/exec.(*Cmd).writerDescriptor.func1() 1520s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1520s os/exec.(*Cmd).Start.func2(0xc0003da2c0) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1520s created by os/exec.(*Cmd).Start in goroutine 101 1520s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1520s 1520s goroutine 40 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc00021b340) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00021b340) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc00021b340, 0xc0002003f0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 41 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc00021b6c0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00021b6c0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc00021b6c0, 0xc000200450) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 42 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc00021ba40) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00021ba40) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc00021ba40, 0xc0002004a0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 43 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc00021bdc0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00021bdc0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc00021bdc0, 0xc0002004f0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 44 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc0002401c0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0002401c0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc0002401c0, 0xc000200540) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 45 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc000240540) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000240540) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc000240540, 0xc000200590) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 46 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc0002408c0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0002408c0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc0002408c0, 0xc0002005e0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 47 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc000240c40) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000240c40) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc000240c40, 0xc000200630) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 49 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc000241340) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000241340) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc000241340, 0xc0002006d0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 98 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc0002416c0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc0002416c0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc0002416c0, 0xc000200720) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 99 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc000241a40) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000241a40) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc000241a40, 0xc000200770) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 100 [chan receive]: 1520s testing.(*testState).waitParallel(0xc000021090) 1520s /usr/lib/go-1.25/src/testing/testing.go:2116 +0x128 1520s testing.(*T).Parallel(0xc000241dc0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1709 +0x2d4 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc000241dc0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:699 +0x42 1520s testing.tRunner(0xc000241dc0, 0xc0002007c0) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 101 [syscall]: 1520s syscall.Syscall6(0x119, 0x3, 0x10, 0xc0000f38d0, 0x4, 0xc0003d4120, 0x0) 1520s /usr/lib/go-1.25/src/syscall/syscall_linux.go:96 +0x18 1520s internal/syscall/unix.Waitid(0x3, 0x10, 0xc0000f38d0, 0x4, 0xc0003d4120) 1520s /usr/lib/go-1.25/src/internal/syscall/unix/waitid_linux.go:18 +0x6c 1520s os.(*Process).pidfdWait.func1(...) 1520s /usr/lib/go-1.25/src/os/pidfd_linux.go:109 1520s os.ignoringEINTR(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:256 1520s os.(*Process).pidfdWait(0xc000080e40) 1520s /usr/lib/go-1.25/src/os/pidfd_linux.go:108 +0x178 1520s os.(*Process).wait(0xc000080e40) 1520s /usr/lib/go-1.25/src/os/exec_unix.go:25 +0x3c 1520s os.(*Process).Wait(...) 1520s /usr/lib/go-1.25/src/os/exec.go:340 1520s os/exec.(*Cmd).Wait(0xc0000bb200) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:922 +0x4a 1520s os/exec.(*Cmd).Run(0xc0000bb200) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:626 +0x66 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc0000bb200) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:226 +0x1b6 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.overrideSSLCertificate({0x8b3de8, 0xce4040}, 0xc0003ff830, 0xc0000da700) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:192 +0x148 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.normalizeLandscapeConfig({0x8b3de8, 0xce4040}, 0xc0003ff830, 0xc000258100) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:158 +0x206 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).fixAndEnableLandscapeFromConfig(0xc0003ff830, {0x8b3de8, 0xce4040}, {0xc0002907e0, 0x119}, 0x0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:70 +0x1b6 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).EnsureValidLandscapeConfig(0xc0003ff830, {0x8b3de8, 0xce4040}) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:51 +0x20a 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00024c1c0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:739 +0x8a0 1520s testing.tRunner(0xc00024c1c0, 0xc000200810) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 102 [syscall]: 1520s syscall.Syscall6(0x119, 0x3, 0xf, 0xc0004ab8d0, 0x4, 0xc000436900, 0x0) 1520s /usr/lib/go-1.25/src/syscall/syscall_linux.go:96 +0x18 1520s internal/syscall/unix.Waitid(0x3, 0xf, 0xc0004ab8d0, 0x4, 0xc000436900) 1520s /usr/lib/go-1.25/src/internal/syscall/unix/waitid_linux.go:18 +0x6c 1520s os.(*Process).pidfdWait.func1(...) 1520s /usr/lib/go-1.25/src/os/pidfd_linux.go:109 1520s os.ignoringEINTR(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:256 1520s os.(*Process).pidfdWait(0xc000230340) 1520s /usr/lib/go-1.25/src/os/pidfd_linux.go:108 +0x178 1520s os.(*Process).wait(0xc000230340) 1520s /usr/lib/go-1.25/src/os/exec_unix.go:25 +0x3c 1520s os.(*Process).Wait(...) 1520s /usr/lib/go-1.25/src/os/exec.go:340 1520s os/exec.(*Cmd).Wait(0xc0000ba900) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:922 +0x4a 1520s os/exec.(*Cmd).Run(0xc0000ba900) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:626 +0x66 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.runCommand(0xc0000ba900) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system.go:226 +0x1b6 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.overrideSSLCertificate({0x8b3de8, 0xce4040}, 0xc0003ff2c0, 0xc000386460) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:192 +0x148 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.normalizeLandscapeConfig({0x8b3de8, 0xce4040}, 0xc0003ff2c0, 0xc0000b0b00) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:158 +0x206 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).fixAndEnableLandscapeFromConfig(0xc0003ff2c0, {0x8b3de8, 0xce4040}, {0xc0000de480, 0x119}, 0x0) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:70 +0x1b6 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system.(*System).EnsureValidLandscapeConfig(0xc0003ff2c0, {0x8b3de8, 0xce4040}) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/landscape.go:51 +0x20a 1520s github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system_test.TestEnsureValidLandscapeConfig.func1(0xc00024c540) 1520s /tmp/autopkgtest.c2k1fp/build.FH8/src/internal/system/system_test.go:739 +0x8a0 1520s testing.tRunner(0xc00024c540, 0xc000200860) 1520s /usr/lib/go-1.25/src/testing/testing.go:1934 +0x12e 1520s created by testing.(*T).Run in goroutine 18 1520s /usr/lib/go-1.25/src/testing/testing.go:1997 +0x548 1520s 1520s goroutine 235 [IO wait]: 1520s internal/poll.runtime_pollWait(0x3ffbcf75800, 0x72) 1520s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1520s internal/poll.(*pollDesc).wait(0xc000492260, 0x72, 0x1) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1520s internal/poll.(*pollDesc).waitRead(...) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1520s internal/poll.(*FD).Read(0xc000492240, {0xc0003e4800, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1520s os.(*File).read(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:29 1520s os.(*File).Read(0xc000220060, {0xc0003e4800, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1520s bytes.(*Buffer).ReadFrom(0xc0001a2330, {0x8ae820, 0xc000220090}) 1520s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2330}, {0x8ae820, 0xc000220090}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os.genericWriteTo(0xc000220060, {0x8ae9e0, 0xc0001a2330}) 1520s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1520s os.(*File).WriteTo(0xc000220060, {0x8ae9e0, 0xc0001a2330}) 1520s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2330}, {0x8ae8a0, 0xc000220060}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os/exec.(*Cmd).writerDescriptor.func1() 1520s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1520s os/exec.(*Cmd).Start.func2(0xc0003da100) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1520s created by os/exec.(*Cmd).Start in goroutine 102 1520s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1520s 1520s goroutine 236 [IO wait]: 1520s internal/poll.runtime_pollWait(0x3ffbcf75e00, 0x72) 1520s /usr/lib/go-1.25/src/runtime/netpoll.go:351 +0xfe 1520s internal/poll.(*pollDesc).wait(0xc000492380, 0x72, 0x1) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:84 +0x42 1520s internal/poll.(*pollDesc).waitRead(...) 1520s /usr/lib/go-1.25/src/internal/poll/fd_poll_runtime.go:89 1520s internal/poll.(*FD).Read(0xc000492360, {0xc0003e4a00, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/internal/poll/fd_unix.go:165 +0x27a 1520s os.(*File).read(...) 1520s /usr/lib/go-1.25/src/os/file_posix.go:29 1520s os.(*File).Read(0xc000220078, {0xc0003e4a00, 0x200, 0x200}) 1520s /usr/lib/go-1.25/src/os/file.go:144 +0x98 1520s bytes.(*Buffer).ReadFrom(0xc0001a2360, {0x8ae820, 0xc000220098}) 1520s /usr/lib/go-1.25/src/bytes/buffer.go:217 +0xba 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2360}, {0x8ae820, 0xc000220098}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:415 +0x1ce 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os.genericWriteTo(0xc000220078, {0x8ae9e0, 0xc0001a2360}) 1520s /usr/lib/go-1.25/src/os/file.go:295 +0x76 1520s os.(*File).WriteTo(0xc000220078, {0x8ae9e0, 0xc0001a2360}) 1520s /usr/lib/go-1.25/src/os/file.go:273 +0xfc 1520s io.copyBuffer({0x8ae9e0, 0xc0001a2360}, {0x8ae8a0, 0xc000220078}, {0x0, 0x0, 0x0}) 1520s /usr/lib/go-1.25/src/io/io.go:411 +0xc2 1520s io.Copy(...) 1520s /usr/lib/go-1.25/src/io/io.go:388 1520s os/exec.(*Cmd).writerDescriptor.func1() 1520s /usr/lib/go-1.25/src/os/exec/exec.go:596 +0x5c 1520s os/exec.(*Cmd).Start.func2(0xc0003da140) 1520s /usr/lib/go-1.25/src/os/exec/exec.go:749 +0x4c 1520s created by os/exec.(*Cmd).Start in goroutine 102 1520s /usr/lib/go-1.25/src/os/exec/exec.go:748 +0xa6a 1520s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 600.226s 1520s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 1520s FAIL 1523s autopkgtest [15:26:16]: test command1: -----------------------] 1526s command1 FAIL non-zero exit status 1 1526s autopkgtest [15:26:19]: test command1: - - - - - - - - - - results - - - - - - - - - - 1527s autopkgtest [15:26:20]: @@@@@@@@@@@@@@@@@@@@ summary 1527s command1 FAIL non-zero exit status 1