0s autopkgtest [08:24:56]: starting date and time: 2024-04-19 08:24:56+0000 0s autopkgtest [08:24:56]: git checkout: 43bc6cdf gitlab-ci: do not include the salsa pipeline 0s autopkgtest [08:24:56]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2o8hr2pb/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:wsl-pro-service --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=wsl-pro-service/0.1.4 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-19.secgroup --name adt-noble-ppc64el-wsl-pro-service-20240419-082455-juju-7f2275-prod-proposed-migration-environment-2-cec2ee3c-232b-4a8b-9202-da64705b4d81 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 114s autopkgtest [08:26:50]: testbed dpkg architecture: ppc64el 114s autopkgtest [08:26:50]: testbed apt version: 2.7.14build2 114s autopkgtest [08:26:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 115s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 115s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [12.3 kB] 115s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [55.6 kB] 115s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [297 kB] 115s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2340 B] 115s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [95.2 kB] 115s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 115s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [976 B] 115s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 115s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [403 kB] 115s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 115s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [1004 B] 115s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 118s Fetched 996 kB in 1s (1015 kB/s) 118s Reading package lists... 120s sh: 4: dhclient: not found 120s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade... 121s The following packages will be upgraded: 121s apport apport-core-dump-handler initramfs-tools initramfs-tools-bin 121s initramfs-tools-core libkeyutils1 libnetplan1 libnuma1 libunwind8 make 121s man-db netcat-openbsd netplan-generator netplan.io numactl python3-apport 121s python3-netplan python3-problem-report 121s 18 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Need to get 2266 kB of archives. 121s After this operation, 19.5 kB disk space will be freed. 121s Get:1 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-problem-report all 2.28.1-0ubuntu1 [23.8 kB] 121s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-apport all 2.28.1-0ubuntu1 [92.1 kB] 121s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el apport-core-dump-handler all 2.28.1-0ubuntu1 [16.8 kB] 121s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el apport all 2.28.1-0ubuntu1 [83.5 kB] 121s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el libkeyutils1 ppc64el 1.6.3-3build1 [11.0 kB] 121s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el netplan-generator ppc64el 1.0-2ubuntu1 [59.3 kB] 121s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-netplan ppc64el 1.0-2ubuntu1 [21.8 kB] 121s Get:8 http://ftpmaster.internal/ubuntu noble/main ppc64el initramfs-tools all 0.142ubuntu25 [9054 B] 121s Get:9 http://ftpmaster.internal/ubuntu noble/main ppc64el initramfs-tools-core all 0.142ubuntu25 [50.1 kB] 121s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el initramfs-tools-bin ppc64el 0.142ubuntu25 [21.1 kB] 121s Get:11 http://ftpmaster.internal/ubuntu noble/main ppc64el netplan.io ppc64el 1.0-2ubuntu1 [66.6 kB] 122s Get:12 http://ftpmaster.internal/ubuntu noble/main ppc64el libnetplan1 ppc64el 1.0-2ubuntu1 [138 kB] 122s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el netcat-openbsd ppc64el 1.226-1ubuntu2 [46.2 kB] 122s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el libnuma1 ppc64el 2.0.18-1build1 [28.2 kB] 122s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el man-db ppc64el 2.12.0-4build2 [1281 kB] 122s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libunwind8 ppc64el 1.6.2-3build1 [60.1 kB] 122s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el make ppc64el 4.3-4.1build2 [214 kB] 122s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el numactl ppc64el 2.0.18-1build1 [43.9 kB] 122s Preconfiguring packages ... 122s Fetched 2266 kB in 1s (3012 kB/s) 122s (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 ... 71981 files and directories currently installed.) 122s Preparing to unpack .../00-python3-problem-report_2.28.1-0ubuntu1_all.deb ... 122s Unpacking python3-problem-report (2.28.1-0ubuntu1) over (2.28.0-0ubuntu1) ... 122s Preparing to unpack .../01-python3-apport_2.28.1-0ubuntu1_all.deb ... 122s Unpacking python3-apport (2.28.1-0ubuntu1) over (2.28.0-0ubuntu1) ... 122s Preparing to unpack .../02-apport-core-dump-handler_2.28.1-0ubuntu1_all.deb ... 122s Unpacking apport-core-dump-handler (2.28.1-0ubuntu1) over (2.28.0-0ubuntu1) ... 122s Preparing to unpack .../03-apport_2.28.1-0ubuntu1_all.deb ... 122s Unpacking apport (2.28.1-0ubuntu1) over (2.28.0-0ubuntu1) ... 122s Preparing to unpack .../04-libkeyutils1_1.6.3-3build1_ppc64el.deb ... 122s Unpacking libkeyutils1:ppc64el (1.6.3-3build1) over (1.6.3-3) ... 122s Preparing to unpack .../05-netplan-generator_1.0-2ubuntu1_ppc64el.deb ... 122s Adding 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 122s Unpacking netplan-generator (1.0-2ubuntu1) over (1.0-2build1) ... 122s Preparing to unpack .../06-python3-netplan_1.0-2ubuntu1_ppc64el.deb ... 122s Unpacking python3-netplan (1.0-2ubuntu1) over (1.0-2build1) ... 122s Preparing to unpack .../07-initramfs-tools_0.142ubuntu25_all.deb ... 122s Unpacking initramfs-tools (0.142ubuntu25) over (0.142ubuntu24) ... 123s Preparing to unpack .../08-initramfs-tools-core_0.142ubuntu25_all.deb ... 123s Unpacking initramfs-tools-core (0.142ubuntu25) over (0.142ubuntu24) ... 123s Preparing to unpack .../09-initramfs-tools-bin_0.142ubuntu25_ppc64el.deb ... 123s Unpacking initramfs-tools-bin (0.142ubuntu25) over (0.142ubuntu24) ... 123s Preparing to unpack .../10-netplan.io_1.0-2ubuntu1_ppc64el.deb ... 123s Unpacking netplan.io (1.0-2ubuntu1) over (1.0-2build1) ... 123s Preparing to unpack .../11-libnetplan1_1.0-2ubuntu1_ppc64el.deb ... 123s Unpacking libnetplan1:ppc64el (1.0-2ubuntu1) over (1.0-2build1) ... 123s Preparing to unpack .../12-netcat-openbsd_1.226-1ubuntu2_ppc64el.deb ... 123s Unpacking netcat-openbsd (1.226-1ubuntu2) over (1.226-1ubuntu1) ... 123s Preparing to unpack .../13-libnuma1_2.0.18-1build1_ppc64el.deb ... 123s Unpacking libnuma1:ppc64el (2.0.18-1build1) over (2.0.18-1) ... 123s Preparing to unpack .../14-man-db_2.12.0-4build2_ppc64el.deb ... 123s Unpacking man-db (2.12.0-4build2) over (2.12.0-4build1) ... 123s Preparing to unpack .../15-libunwind8_1.6.2-3build1_ppc64el.deb ... 123s Unpacking libunwind8:ppc64el (1.6.2-3build1) over (1.6.2-3) ... 123s Preparing to unpack .../16-make_4.3-4.1build2_ppc64el.deb ... 123s Unpacking make (4.3-4.1build2) over (4.3-4.1build1) ... 123s Preparing to unpack .../17-numactl_2.0.18-1build1_ppc64el.deb ... 123s Unpacking numactl (2.0.18-1build1) over (2.0.18-1) ... 123s Setting up libkeyutils1:ppc64el (1.6.3-3build1) ... 123s Setting up python3-problem-report (2.28.1-0ubuntu1) ... 123s Setting up netcat-openbsd (1.226-1ubuntu2) ... 123s Setting up libnetplan1:ppc64el (1.0-2ubuntu1) ... 123s Setting up man-db (2.12.0-4build2) ... 123s Updating database of manual pages ... 125s man-db.service is a disabled or a static unit not running, not starting it. 125s Setting up libunwind8:ppc64el (1.6.2-3build1) ... 125s Setting up python3-apport (2.28.1-0ubuntu1) ... 125s Setting up make (4.3-4.1build2) ... 125s Setting up libnuma1:ppc64el (2.0.18-1build1) ... 125s Setting up python3-netplan (1.0-2ubuntu1) ... 125s Setting up netplan-generator (1.0-2ubuntu1) ... 126s Removing 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 126s Setting up initramfs-tools-bin (0.142ubuntu25) ... 126s Setting up numactl (2.0.18-1build1) ... 126s Setting up netplan.io (1.0-2ubuntu1) ... 126s Setting up initramfs-tools-core (0.142ubuntu25) ... 126s Setting up initramfs-tools (0.142ubuntu25) ... 126s update-initramfs: deferring update (trigger activated) 126s Setting up apport-core-dump-handler (2.28.1-0ubuntu1) ... 127s Setting up apport (2.28.1-0ubuntu1) ... 127s Installing new version of config file /etc/apport/crashdb.conf ... 127s apport-autoreport.service is a disabled or a static unit not running, not starting it. 127s Processing triggers for dbus (1.14.10-4ubuntu4) ... 127s Processing triggers for libc-bin (2.39-0ubuntu8) ... 127s Processing triggers for initramfs-tools (0.142ubuntu25) ... 127s update-initramfs: Generating /boot/initrd.img-6.8.0-22-generic 127s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 133s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 134s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 134s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 134s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 135s Reading package lists... 135s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Calculating upgrade... 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s autopkgtest [08:27:12]: rebooting testbed after setup commands that affected boot 174s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 184s autopkgtest [08:28:00]: testbed running kernel: Linux 6.8.0-22-generic #22-Ubuntu SMP Thu Apr 4 22:47:57 UTC 2024 187s autopkgtest [08:28:03]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 190s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/universe wsl-pro-service 0.1.4 (dsc) [1420 B] 190s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe wsl-pro-service 0.1.4 (tar) [1762 kB] 190s gpgv: Signature made Fri Apr 19 05:59:17 2024 UTC 190s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 190s gpgv: Can't check signature: No public key 190s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.4.dsc: no acceptable signature found 191s autopkgtest [08:28:07]: testing package wsl-pro-service version 0.1.4 191s autopkgtest [08:28:07]: build not needed 282s autopkgtest [08:29:38]: test command1: preparing testbed 283s Reading package lists... 283s Building dependency tree... 283s Reading state information... 284s Starting pkgProblemResolver with broken count: 0 284s Starting 2 pkgProblemResolver with broken count: 0 284s Done 284s The following additional packages will be installed: 284s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 284s cpp-13-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper debugedit 284s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 284s g++-13-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-13 284s gcc-13-base gcc-13-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext 284s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 284s libarchive-zip-perl libasan8 libatomic1 libcc1-0 libdebhelper-perl 284s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 284s liblsan0 libmpc3 libquadmath0 libstdc++-13-dev libsub-override-perl libtool 284s libtsan2 libubsan1 m4 po-debconf 284s Suggested packages: 284s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 284s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 284s gcc-doc gdb-powerpc64le-linux-gnu gettext-doc libasprintf-dev 284s libgettextpo-dev bzr | brz git mercurial subversion libstdc++-13-doc 284s libtool-doc gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 284s Recommended packages: 284s pkg-config libarchive-cpio-perl libltdl-dev libmail-sendmail-perl 284s The following NEW packages will be installed: 284s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 284s cpp cpp-13 cpp-13-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper 284s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 284s g++-13 g++-13-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-13 284s gcc-13-base gcc-13-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext 284s golang-1.22-go golang-1.22-src golang-go golang-src intltool-debian 284s libarchive-zip-perl libasan8 libatomic1 libcc1-0 libdebhelper-perl 284s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 284s liblsan0 libmpc3 libquadmath0 libstdc++-13-dev libsub-override-perl libtool 284s libtsan2 libubsan1 m4 po-debconf 284s 0 upgraded, 52 newly installed, 0 to remove and 0 not upgraded. 284s Need to get 105 MB/105 MB of archives. 284s After this operation, 461 MB of additional disk space will be used. 284s Get:1 /tmp/autopkgtest.td9BQJ/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [764 B] 284s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el m4 ppc64el 1.4.19-4build1 [278 kB] 284s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el autoconf all 2.71-3 [339 kB] 285s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el autotools-dev all 20220109.1 [44.9 kB] 285s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el automake all 1:1.16.5-1.3ubuntu1 [558 kB] 285s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el autopoint all 0.21-14ubuntu2 [422 kB] 285s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13-base ppc64el 13.2.0-23ubuntu4 [49.0 kB] 285s Get:8 http://ftpmaster.internal/ubuntu noble/main ppc64el libisl23 ppc64el 0.26-3build1 [886 kB] 286s Get:9 http://ftpmaster.internal/ubuntu noble/main ppc64el libmpc3 ppc64el 1.3.1-1build1 [62.1 kB] 286s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13-powerpc64le-linux-gnu ppc64el 13.2.0-23ubuntu4 [10.7 MB] 288s Get:11 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13 ppc64el 13.2.0-23ubuntu4 [1038 B] 288s Get:12 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [5330 B] 288s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp ppc64el 4:13.2.0-7ubuntu1 [22.5 kB] 288s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el libcc1-0 ppc64el 14-20240412-0ubuntu1 [48.1 kB] 288s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el libgomp1 ppc64el 14-20240412-0ubuntu1 [161 kB] 288s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libitm1 ppc64el 14-20240412-0ubuntu1 [32.4 kB] 288s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el libatomic1 ppc64el 14-20240412-0ubuntu1 [10.7 kB] 288s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el libasan8 ppc64el 14-20240412-0ubuntu1 [2971 kB] 288s Get:19 http://ftpmaster.internal/ubuntu noble/main ppc64el liblsan0 ppc64el 14-20240412-0ubuntu1 [1327 kB] 288s Get:20 http://ftpmaster.internal/ubuntu noble/main ppc64el libtsan2 ppc64el 14-20240412-0ubuntu1 [2709 kB] 289s Get:21 http://ftpmaster.internal/ubuntu noble/main ppc64el libubsan1 ppc64el 14-20240412-0ubuntu1 [1196 kB] 289s Get:22 http://ftpmaster.internal/ubuntu noble/main ppc64el libquadmath0 ppc64el 14-20240412-0ubuntu1 [158 kB] 289s Get:23 http://ftpmaster.internal/ubuntu noble/main ppc64el libgcc-13-dev ppc64el 13.2.0-23ubuntu4 [1580 kB] 289s Get:24 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13-powerpc64le-linux-gnu ppc64el 13.2.0-23ubuntu4 [20.6 MB] 290s Get:25 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13 ppc64el 13.2.0-23ubuntu4 [482 kB] 290s Get:26 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [1224 B] 290s Get:27 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc ppc64el 4:13.2.0-7ubuntu1 [5022 B] 290s Get:28 http://ftpmaster.internal/ubuntu noble/main ppc64el libstdc++-13-dev ppc64el 13.2.0-23ubuntu4 [2512 kB] 290s Get:29 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13-powerpc64le-linux-gnu ppc64el 13.2.0-23ubuntu4 [12.2 MB] 290s Get:30 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13 ppc64el 13.2.0-23ubuntu4 [14.5 kB] 290s Get:31 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [968 B] 290s Get:32 http://ftpmaster.internal/ubuntu noble/main ppc64el g++ ppc64el 4:13.2.0-7ubuntu1 [1086 B] 290s Get:33 http://ftpmaster.internal/ubuntu noble/main ppc64el build-essential ppc64el 12.10ubuntu1 [4936 B] 290s Get:34 http://ftpmaster.internal/ubuntu noble/main ppc64el libdebhelper-perl all 13.14.1ubuntu5 [89.8 kB] 290s Get:35 http://ftpmaster.internal/ubuntu noble/main ppc64el libtool all 2.4.7-7build1 [166 kB] 290s Get:36 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-autoreconf all 20 [16.1 kB] 290s Get:37 http://ftpmaster.internal/ubuntu noble/main ppc64el libarchive-zip-perl all 1.68-1 [90.2 kB] 290s Get:38 http://ftpmaster.internal/ubuntu noble/main ppc64el libsub-override-perl all 0.10-1 [10.0 kB] 290s Get:39 http://ftpmaster.internal/ubuntu noble/main ppc64el libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 290s Get:40 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-strip-nondeterminism all 1.13.1-1 [5362 B] 290s Get:41 http://ftpmaster.internal/ubuntu noble/main ppc64el debugedit ppc64el 1:5.0-5build2 [51.4 kB] 290s Get:42 http://ftpmaster.internal/ubuntu noble/main ppc64el dwz ppc64el 0.15-1build6 [142 kB] 290s Get:43 http://ftpmaster.internal/ubuntu noble/main ppc64el gettext ppc64el 0.21-14ubuntu2 [985 kB] 290s Get:44 http://ftpmaster.internal/ubuntu noble/main ppc64el intltool-debian all 0.35.0+20060710.6 [23.2 kB] 290s Get:45 http://ftpmaster.internal/ubuntu noble/main ppc64el po-debconf all 1.0.21+nmu1 [233 kB] 290s Get:46 http://ftpmaster.internal/ubuntu noble/main ppc64el debhelper all 13.14.1ubuntu5 [869 kB] 290s Get:47 http://ftpmaster.internal/ubuntu noble/universe ppc64el dh-apport all 2.28.1-0ubuntu1 [17.1 kB] 290s Get:48 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-1.22-src all 1.22.2-2 [19.7 MB] 291s Get:49 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-1.22-go ppc64el 1.22.2-2 [23.5 MB] 292s Get:50 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-src all 2:1.22~2build1 [5078 B] 292s Get:51 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-go ppc64el 2:1.22~2build1 [44.0 kB] 292s Get:52 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-golang all 1.62 [25.2 kB] 292s Fetched 105 MB in 8s (13.4 MB/s) 292s Selecting previously unselected package m4. 292s (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 ... 71980 files and directories currently installed.) 292s Preparing to unpack .../00-m4_1.4.19-4build1_ppc64el.deb ... 292s Unpacking m4 (1.4.19-4build1) ... 292s Selecting previously unselected package autoconf. 292s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 292s Unpacking autoconf (2.71-3) ... 292s Selecting previously unselected package autotools-dev. 292s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 292s Unpacking autotools-dev (20220109.1) ... 292s Selecting previously unselected package automake. 292s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 292s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 292s Selecting previously unselected package autopoint. 292s Preparing to unpack .../04-autopoint_0.21-14ubuntu2_all.deb ... 292s Unpacking autopoint (0.21-14ubuntu2) ... 292s Selecting previously unselected package gcc-13-base:ppc64el. 292s Preparing to unpack .../05-gcc-13-base_13.2.0-23ubuntu4_ppc64el.deb ... 292s Unpacking gcc-13-base:ppc64el (13.2.0-23ubuntu4) ... 292s Selecting previously unselected package libisl23:ppc64el. 292s Preparing to unpack .../06-libisl23_0.26-3build1_ppc64el.deb ... 292s Unpacking libisl23:ppc64el (0.26-3build1) ... 292s Selecting previously unselected package libmpc3:ppc64el. 292s Preparing to unpack .../07-libmpc3_1.3.1-1build1_ppc64el.deb ... 292s Unpacking libmpc3:ppc64el (1.3.1-1build1) ... 292s Selecting previously unselected package cpp-13-powerpc64le-linux-gnu. 292s Preparing to unpack .../08-cpp-13-powerpc64le-linux-gnu_13.2.0-23ubuntu4_ppc64el.deb ... 292s Unpacking cpp-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 293s Selecting previously unselected package cpp-13. 293s Preparing to unpack .../09-cpp-13_13.2.0-23ubuntu4_ppc64el.deb ... 293s Unpacking cpp-13 (13.2.0-23ubuntu4) ... 293s Selecting previously unselected package cpp-powerpc64le-linux-gnu. 293s Preparing to unpack .../10-cpp-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 293s Unpacking cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 293s Selecting previously unselected package cpp. 293s Preparing to unpack .../11-cpp_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 293s Unpacking cpp (4:13.2.0-7ubuntu1) ... 293s Selecting previously unselected package libcc1-0:ppc64el. 293s Preparing to unpack .../12-libcc1-0_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libcc1-0:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libgomp1:ppc64el. 293s Preparing to unpack .../13-libgomp1_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libgomp1:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libitm1:ppc64el. 293s Preparing to unpack .../14-libitm1_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libitm1:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libatomic1:ppc64el. 293s Preparing to unpack .../15-libatomic1_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libatomic1:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libasan8:ppc64el. 293s Preparing to unpack .../16-libasan8_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libasan8:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package liblsan0:ppc64el. 293s Preparing to unpack .../17-liblsan0_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking liblsan0:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libtsan2:ppc64el. 293s Preparing to unpack .../18-libtsan2_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libtsan2:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libubsan1:ppc64el. 293s Preparing to unpack .../19-libubsan1_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libubsan1:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libquadmath0:ppc64el. 293s Preparing to unpack .../20-libquadmath0_14-20240412-0ubuntu1_ppc64el.deb ... 293s Unpacking libquadmath0:ppc64el (14-20240412-0ubuntu1) ... 293s Selecting previously unselected package libgcc-13-dev:ppc64el. 293s Preparing to unpack .../21-libgcc-13-dev_13.2.0-23ubuntu4_ppc64el.deb ... 293s Unpacking libgcc-13-dev:ppc64el (13.2.0-23ubuntu4) ... 293s Selecting previously unselected package gcc-13-powerpc64le-linux-gnu. 293s Preparing to unpack .../22-gcc-13-powerpc64le-linux-gnu_13.2.0-23ubuntu4_ppc64el.deb ... 293s Unpacking gcc-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 293s Selecting previously unselected package gcc-13. 293s Preparing to unpack .../23-gcc-13_13.2.0-23ubuntu4_ppc64el.deb ... 293s Unpacking gcc-13 (13.2.0-23ubuntu4) ... 294s Selecting previously unselected package gcc-powerpc64le-linux-gnu. 294s Preparing to unpack .../24-gcc-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 294s Unpacking gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 294s Selecting previously unselected package gcc. 294s Preparing to unpack .../25-gcc_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 294s Unpacking gcc (4:13.2.0-7ubuntu1) ... 294s Selecting previously unselected package libstdc++-13-dev:ppc64el. 294s Preparing to unpack .../26-libstdc++-13-dev_13.2.0-23ubuntu4_ppc64el.deb ... 294s Unpacking libstdc++-13-dev:ppc64el (13.2.0-23ubuntu4) ... 294s Selecting previously unselected package g++-13-powerpc64le-linux-gnu. 294s Preparing to unpack .../27-g++-13-powerpc64le-linux-gnu_13.2.0-23ubuntu4_ppc64el.deb ... 294s Unpacking g++-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 294s Selecting previously unselected package g++-13. 294s Preparing to unpack .../28-g++-13_13.2.0-23ubuntu4_ppc64el.deb ... 294s Unpacking g++-13 (13.2.0-23ubuntu4) ... 294s Selecting previously unselected package g++-powerpc64le-linux-gnu. 294s Preparing to unpack .../29-g++-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 294s Unpacking g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 294s Selecting previously unselected package g++. 294s Preparing to unpack .../30-g++_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 294s Unpacking g++ (4:13.2.0-7ubuntu1) ... 294s Selecting previously unselected package build-essential. 294s Preparing to unpack .../31-build-essential_12.10ubuntu1_ppc64el.deb ... 294s Unpacking build-essential (12.10ubuntu1) ... 294s Selecting previously unselected package libdebhelper-perl. 294s Preparing to unpack .../32-libdebhelper-perl_13.14.1ubuntu5_all.deb ... 294s Unpacking libdebhelper-perl (13.14.1ubuntu5) ... 294s Selecting previously unselected package libtool. 294s Preparing to unpack .../33-libtool_2.4.7-7build1_all.deb ... 294s Unpacking libtool (2.4.7-7build1) ... 294s Selecting previously unselected package dh-autoreconf. 294s Preparing to unpack .../34-dh-autoreconf_20_all.deb ... 294s Unpacking dh-autoreconf (20) ... 294s Selecting previously unselected package libarchive-zip-perl. 294s Preparing to unpack .../35-libarchive-zip-perl_1.68-1_all.deb ... 294s Unpacking libarchive-zip-perl (1.68-1) ... 294s Selecting previously unselected package libsub-override-perl. 294s Preparing to unpack .../36-libsub-override-perl_0.10-1_all.deb ... 294s Unpacking libsub-override-perl (0.10-1) ... 294s Selecting previously unselected package libfile-stripnondeterminism-perl. 294s Preparing to unpack .../37-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 294s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 294s Selecting previously unselected package dh-strip-nondeterminism. 294s Preparing to unpack .../38-dh-strip-nondeterminism_1.13.1-1_all.deb ... 294s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 294s Selecting previously unselected package debugedit. 294s Preparing to unpack .../39-debugedit_1%3a5.0-5build2_ppc64el.deb ... 294s Unpacking debugedit (1:5.0-5build2) ... 294s Selecting previously unselected package dwz. 294s Preparing to unpack .../40-dwz_0.15-1build6_ppc64el.deb ... 294s Unpacking dwz (0.15-1build6) ... 294s Selecting previously unselected package gettext. 294s Preparing to unpack .../41-gettext_0.21-14ubuntu2_ppc64el.deb ... 294s Unpacking gettext (0.21-14ubuntu2) ... 294s Selecting previously unselected package intltool-debian. 294s Preparing to unpack .../42-intltool-debian_0.35.0+20060710.6_all.deb ... 294s Unpacking intltool-debian (0.35.0+20060710.6) ... 294s Selecting previously unselected package po-debconf. 294s Preparing to unpack .../43-po-debconf_1.0.21+nmu1_all.deb ... 294s Unpacking po-debconf (1.0.21+nmu1) ... 294s Selecting previously unselected package debhelper. 294s Preparing to unpack .../44-debhelper_13.14.1ubuntu5_all.deb ... 294s Unpacking debhelper (13.14.1ubuntu5) ... 294s Selecting previously unselected package dh-apport. 294s Preparing to unpack .../45-dh-apport_2.28.1-0ubuntu1_all.deb ... 294s Unpacking dh-apport (2.28.1-0ubuntu1) ... 294s Selecting previously unselected package golang-1.22-src. 294s Preparing to unpack .../46-golang-1.22-src_1.22.2-2_all.deb ... 294s Unpacking golang-1.22-src (1.22.2-2) ... 297s Selecting previously unselected package golang-1.22-go. 297s Preparing to unpack .../47-golang-1.22-go_1.22.2-2_ppc64el.deb ... 297s Unpacking golang-1.22-go (1.22.2-2) ... 298s Selecting previously unselected package golang-src. 298s Preparing to unpack .../48-golang-src_2%3a1.22~2build1_all.deb ... 298s Unpacking golang-src (2:1.22~2build1) ... 298s Selecting previously unselected package golang-go:ppc64el. 298s Preparing to unpack .../49-golang-go_2%3a1.22~2build1_ppc64el.deb ... 298s Unpacking golang-go:ppc64el (2:1.22~2build1) ... 298s Selecting previously unselected package dh-golang. 298s Preparing to unpack .../50-dh-golang_1.62_all.deb ... 298s Unpacking dh-golang (1.62) ... 298s Selecting previously unselected package autopkgtest-satdep. 298s Preparing to unpack .../51-1-autopkgtest-satdep.deb ... 298s Unpacking autopkgtest-satdep (0) ... 298s Setting up libarchive-zip-perl (1.68-1) ... 298s Setting up libdebhelper-perl (13.14.1ubuntu5) ... 298s Setting up m4 (1.4.19-4build1) ... 298s Setting up libgomp1:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up dh-apport (2.28.1-0ubuntu1) ... 298s Setting up autotools-dev (20220109.1) ... 298s Setting up gcc-13-base:ppc64el (13.2.0-23ubuntu4) ... 298s Setting up golang-1.22-src (1.22.2-2) ... 298s Setting up libquadmath0:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up libmpc3:ppc64el (1.3.1-1build1) ... 298s Setting up libatomic1:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up autopoint (0.21-14ubuntu2) ... 298s Setting up autoconf (2.71-3) ... 298s Setting up libubsan1:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up dwz (0.15-1build6) ... 298s Setting up libasan8:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up debugedit (1:5.0-5build2) ... 298s Setting up libsub-override-perl (0.10-1) ... 298s Setting up libtsan2:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up libisl23:ppc64el (0.26-3build1) ... 298s Setting up cpp-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 298s Setting up golang-src (2:1.22~2build1) ... 298s Setting up libcc1-0:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up liblsan0:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up libitm1:ppc64el (14-20240412-0ubuntu1) ... 298s Setting up cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 298s Setting up automake (1:1.16.5-1.3ubuntu1) ... 298s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 298s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 298s Setting up gettext (0.21-14ubuntu2) ... 298s Setting up cpp-13 (13.2.0-23ubuntu4) ... 298s Setting up golang-1.22-go (1.22.2-2) ... 298s Setting up intltool-debian (0.35.0+20060710.6) ... 298s Setting up dh-strip-nondeterminism (1.13.1-1) ... 298s Setting up libgcc-13-dev:ppc64el (13.2.0-23ubuntu4) ... 298s Setting up cpp (4:13.2.0-7ubuntu1) ... 298s Setting up libstdc++-13-dev:ppc64el (13.2.0-23ubuntu4) ... 298s Setting up golang-go:ppc64el (2:1.22~2build1) ... 298s Setting up po-debconf (1.0.21+nmu1) ... 298s Setting up gcc-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 298s Setting up gcc-13 (13.2.0-23ubuntu4) ... 298s Setting up libtool (2.4.7-7build1) ... 298s Setting up g++-13-powerpc64le-linux-gnu (13.2.0-23ubuntu4) ... 298s Setting up dh-autoreconf (20) ... 298s Setting up gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 298s Setting up g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 298s Setting up g++-13 (13.2.0-23ubuntu4) ... 298s Setting up debhelper (13.14.1ubuntu5) ... 298s Setting up gcc (4:13.2.0-7ubuntu1) ... 298s Setting up g++ (4:13.2.0-7ubuntu1) ... 298s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 298s Setting up build-essential (12.10ubuntu1) ... 298s Setting up dh-golang (1.62) ... 298s Setting up autopkgtest-satdep (0) ... 298s Processing triggers for man-db (2.12.0-4build2) ... 299s Processing triggers for install-info (7.1-3build2) ... 299s Processing triggers for libc-bin (2.39-0ubuntu8) ... 301s (Reading database ... 88572 files and directories currently installed.) 301s Removing autopkgtest-satdep (0) ... 302s autopkgtest [08:29:58]: test command1: ./debian/tests/test 302s autopkgtest [08:29:58]: test command1: [----------------------- 366s === RUN TestRunSignal 366s === RUN TestRunSignal/Send_SIGINT_exits 366s INFO Starting WSL Pro Service version Dev 366s === RUN TestRunSignal/Send_SIGTERM_exits 366s INFO Starting WSL Pro Service version Dev 366s --- PASS: TestRunSignal (0.20s) 366s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 366s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 366s === RUN TestRun 366s === PAUSE TestRun 366s === CONT TestRun 366s === RUN TestRun/Run_and_exit_successfully 366s === PAUSE TestRun/Run_and_exit_successfully 366s === RUN TestRun/Run_and_return_error 366s === PAUSE TestRun/Run_and_return_error 366s === RUN TestRun/Run_and_return_usage_error 366s === PAUSE TestRun/Run_and_return_usage_error 366s === RUN TestRun/Run_and_usage_error_only_does_not_fail 366s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 366s === CONT TestRun/Run_and_exit_successfully 366s INFO Starting WSL Pro Service version Dev 366s === CONT TestRun/Run_and_usage_error_only_does_not_fail 366s INFO Starting WSL Pro Service version Dev 366s === CONT TestRun/Run_and_return_usage_error 366s INFO Starting WSL Pro Service version Dev 366s ERROR context.Background Error requested 366s === CONT TestRun/Run_and_return_error 366s INFO Starting WSL Pro Service version Dev 366s ERROR context.Background Error requested 366s --- PASS: TestRun (0.00s) 366s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 366s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 366s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 366s --- PASS: TestRun/Run_and_return_error (0.10s) 366s PASS 366s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.607s 368s === RUN TestHelp 368s --- PASS: TestHelp (0.00s) 368s === RUN TestCompletion 368s --- PASS: TestCompletion (0.00s) 368s === RUN TestVersion 368s --- PASS: TestVersion (0.00s) 368s === RUN TestNoUsageError 368s --- PASS: TestNoUsageError (0.00s) 368s === RUN TestUsageError 368s === PAUSE TestUsageError 368s === RUN TestCanQuitWhenExecute 368s === PAUSE TestCanQuitWhenExecute 368s === RUN TestCanQuitTwice 368s === PAUSE TestCanQuitTwice 368s === RUN TestAppCanQuitWithoutExecute 368s === PAUSE TestAppCanQuitWithoutExecute 368s === RUN TestAppRunFailsOnComponentsCreationAndQuit 368s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 368s === RUN TestAppGetRootCmd 368s === PAUSE TestAppGetRootCmd 368s === RUN TestWithProMock 368s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 368s --- SKIP: TestWithProMock (0.00s) 368s === RUN TestWithWslPathMock 368s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 368s --- SKIP: TestWithWslPathMock (0.00s) 368s === RUN TestWithWslInfoMock 368s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 368s --- SKIP: TestWithWslInfoMock (0.00s) 368s === RUN TestWithCmdExeMock 368s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 368s --- SKIP: TestWithCmdExeMock (0.00s) 368s === CONT TestUsageError 368s --- PASS: TestUsageError (0.00s) 368s === CONT TestAppGetRootCmd 368s --- PASS: TestAppGetRootCmd (0.00s) 368s === CONT TestAppRunFailsOnComponentsCreationAndQuit 371s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (3.67s) 371s === CONT TestAppCanQuitWithoutExecute 371s service_test.go:140: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 371s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 371s === CONT TestCanQuitTwice 371s time="2024-04-19T08:31:07Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 371s time="2024-04-19T08:31:07Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:75 New() Building new daemon" 373s time="2024-04-19T08:31:09Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 374s time="2024-04-19T08:31:10Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 374s time="2024-04-19T08:31:10Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 375s time="2024-04-19T08:31:11Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:41787" 375s time="2024-04-19T08:31:11Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 375s time="2024-04-19T08:31:11Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.ProAttachCmd requests" 375s time="2024-04-19T08:31:11Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/streams/server.go:196 (*handlingLoop[...]).run() Started serving agentapi.LandscapeConfigCmd requests" 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/streams/server.go:141 (*Server).Serve() Server: sent preface messages to all streams" 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/testutils/mock_agent.go:326 (*mockWSLInstanceService).LandscapeConfigCommands() MockWindowsAgent: LandscapeConfigCommands ready" 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/testutils/mock_agent.go:275 (*mockWSLInstanceService).Connected() MockWindowsAgent: Connected ready" 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/testutils/mock_agent.go:300 (*mockWSLInstanceService).ProAttachmentCommands() MockWindowsAgent: ProAttachmentCommands ready" 376s time="2024-04-19T08:31:12Z" level=warning msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:186 (*Daemon).Serve.func1() Daemon: disconnected from Windows host" 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 376s time="2024-04-19T08:31:12Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.td9BQJ/build.1MQ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 376s --- PASS: TestCanQuitTwice (4.37s) 376s === CONT TestCanQuitWhenExecute 376s time="2024-04-19T08:31:12Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/testutils/mock_agent.go:76 NewMockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37915" 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/cmd/wsl-pro-service/service/service.go:66 New.func1() Debug mode is enabled" 376s time="2024-04-19T08:31:12Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:75 New() Building new daemon" 376s time="2024-04-19T08:31:12Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.td9BQJ/build.1MQ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 376s time="2024-04-19T08:31:12Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" func=github.com/ubuntu/decorate.LogOnErrorContext file="/tmp/autopkgtest.td9BQJ/build.1MQ/src/vendor/github.com/ubuntu/decorate/decorate.go:27" 378s time="2024-04-19T08:31:14Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:153 (*Daemon).Serve.func1() Daemon: connecting to Windows Agent" 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:288 (*Daemon).connect() Daemon: starting connection to Windows Agent via 127.0.0.1:37915" 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:173 (*Daemon).Serve.func1() Daemon: completed connection to Windows Agent" 379s time="2024-04-19T08:31:15Z" level=warning msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:184 (*Daemon).Serve.func1() Daemon: disconnected from Windows host: could not start serving: could not connect: could not connect to GRPC service: rpc error: code = Unavailable desc = connection error: desc = \"transport: Error while dialing: dial tcp 127.0.0.1:37915: connect: connection refused\"" 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:209 (*Daemon).Serve() Reconnecting to Windows host in 1 seconds" 379s time="2024-04-19T08:31:15Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:220 (*Daemon).Quit() Stopping daemon requested." 379s time="2024-04-19T08:31:15Z" level=info msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:226 (*Daemon).Quit() Waiting for active requests to close." 379s time="2024-04-19T08:31:15Z" level=debug msg="/tmp/autopkgtest.td9BQJ/build.1MQ/src/internal/daemon/daemon.go:234 (*Daemon).Quit() All connections have now ended." 379s --- PASS: TestCanQuitWhenExecute (3.29s) 379s PASS 379s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 11.353s 379s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 380s === RUN TestApplyProToken 380s === PAUSE TestApplyProToken 380s === RUN TestApplyLandscapeConfig 380s === PAUSE TestApplyLandscapeConfig 380s === RUN TestWithProMock 380s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 380s --- SKIP: TestWithProMock (0.00s) 380s === RUN TestWithLandscapeConfigMock 380s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 380s --- SKIP: TestWithLandscapeConfigMock (0.00s) 380s === RUN TestWithWslPathMock 380s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 380s --- SKIP: TestWithWslPathMock (0.00s) 380s === RUN TestWithWslInfoMock 380s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 380s --- SKIP: TestWithWslInfoMock (0.00s) 380s === RUN TestWithCmdExeMock 380s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 380s --- SKIP: TestWithCmdExeMock (0.00s) 380s === CONT TestApplyProToken 380s === RUN TestApplyProToken/Success_attaching 380s === PAUSE TestApplyProToken/Success_attaching 380s === RUN TestApplyProToken/Success_detaching 380s === PAUSE TestApplyProToken/Success_detaching 380s === RUN TestApplyProToken/Error_calling_pro_detach 380s === PAUSE TestApplyProToken/Error_calling_pro_detach 380s === RUN TestApplyProToken/Error_calling_pro_attach 380s === PAUSE TestApplyProToken/Error_calling_pro_attach 380s === CONT TestApplyProToken/Success_attaching 380s time="2024-04-19T08:31:16Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 381s === CONT TestApplyLandscapeConfig 381s === RUN TestApplyLandscapeConfig/Success_enabling_Landscape 381s === PAUSE TestApplyLandscapeConfig/Success_enabling_Landscape 381s === RUN TestApplyLandscapeConfig/Success_disabling_Landscape 381s === PAUSE TestApplyLandscapeConfig/Success_disabling_Landscape 381s === RUN TestApplyLandscapeConfig/Error_calling_landscape_disable 381s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_disable 381s === RUN TestApplyLandscapeConfig/Error_calling_landscape_enable 381s === PAUSE TestApplyLandscapeConfig/Error_calling_landscape_enable 381s === CONT TestApplyProToken/Error_calling_pro_attach 381s time="2024-04-19T08:31:17Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 383s === CONT TestApplyProToken/Error_calling_pro_detach 383s time="2024-04-19T08:31:19Z" level=info msg="ApplyProToken: Received token \"12**bc\": attaching" 384s === CONT TestApplyProToken/Success_detaching 384s time="2024-04-19T08:31:20Z" level=info msg="ApplyProToken: Received empty token: detaching" 385s --- PASS: TestApplyProToken (0.00s) 385s --- PASS: TestApplyProToken/Success_attaching (1.83s) 385s --- PASS: TestApplyProToken/Error_calling_pro_attach (1.82s) 385s --- PASS: TestApplyProToken/Error_calling_pro_detach (0.93s) 385s --- PASS: TestApplyProToken/Success_detaching (0.92s) 385s === CONT TestApplyLandscapeConfig/Success_enabling_Landscape 385s time="2024-04-19T08:31:21Z" level=info msg="ApplyLandscapeConfig: received config: registering" 386s === CONT TestApplyLandscapeConfig/Error_calling_landscape_enable 386s time="2024-04-19T08:31:22Z" level=info msg="ApplyLandscapeConfig: received config: registering" 387s === CONT TestApplyLandscapeConfig/Error_calling_landscape_disable 387s time="2024-04-19T08:31:23Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 388s === CONT TestApplyLandscapeConfig/Success_disabling_Landscape 388s time="2024-04-19T08:31:24Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 389s --- PASS: TestApplyLandscapeConfig (0.00s) 389s --- PASS: TestApplyLandscapeConfig/Success_enabling_Landscape (0.91s) 389s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_enable (0.96s) 389s --- PASS: TestApplyLandscapeConfig/Error_calling_landscape_disable (0.92s) 389s --- PASS: TestApplyLandscapeConfig/Success_disabling_Landscape (0.90s) 389s PASS 389s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/commandservice 9.196s 389s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 390s === RUN TestNew 390s === PAUSE TestNew 390s === RUN TestServe 390s === PAUSE TestServe 390s === RUN TestServeAndQuit 390s === PAUSE TestServeAndQuit 390s === RUN TestReconnection 390s === PAUSE TestReconnection 390s === RUN TestWithProMock 390s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 390s --- SKIP: TestWithProMock (0.00s) 390s === RUN TestWithWslPathMock 390s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 390s --- SKIP: TestWithWslPathMock (0.00s) 390s === RUN TestWithWslInfoMock 390s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 390s --- SKIP: TestWithWslInfoMock (0.00s) 390s === RUN TestWithCmdExeMock 390s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 390s --- SKIP: TestWithCmdExeMock (0.00s) 390s === CONT TestNew 390s === RUN TestNew/Error_when_WslPath_returns_error 390s === PAUSE TestNew/Error_when_WslPath_returns_error 390s === RUN TestNew/Success 390s === PAUSE TestNew/Success 390s === CONT TestReconnection 390s === RUN TestReconnection/Success_connecting_after_failing_to_connect 390s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 390s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 390s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 390s === RUN TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 390s === PAUSE TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 390s === CONT TestServeAndQuit 390s === RUN TestServeAndQuit/Success_with_graceful_quit 390s === PAUSE TestServeAndQuit/Success_with_graceful_quit 390s === RUN TestServeAndQuit/Success_with_forceful_quit 390s === PAUSE TestServeAndQuit/Success_with_forceful_quit 390s === RUN TestServeAndQuit/Success_with_double_quit 390s === PAUSE TestServeAndQuit/Success_with_double_quit 390s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 390s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 390s === CONT TestServe 390s === RUN TestServe/No_connection_because_the_port_file_has_a_bad_port 390s === PAUSE TestServe/No_connection_because_the_port_file_has_a_bad_port 390s === RUN TestServe/No_connection_because_the_port_file_has_port_0 390s === PAUSE TestServe/No_connection_because_the_port_file_has_port_0 390s === RUN TestServe/No_connection_because_cannot_read_root_CA_certificate_file 390s === PAUSE TestServe/No_connection_because_cannot_read_root_CA_certificate_file 390s === RUN TestServe/Error_because_the_context_is_pre-cancelled 390s === PAUSE TestServe/Error_because_the_context_is_pre-cancelled 390s === RUN TestServe/Error_because_the_notifier_returns_an_error 390s === PAUSE TestServe/Error_because_the_notifier_returns_an_error 390s === RUN TestServe/Success_with_systemd_notifier_returning_true 390s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 390s === RUN TestServe/No_connection_because_the_port_file_is_empty 390s === PAUSE TestServe/No_connection_because_the_port_file_is_empty 390s === RUN TestServe/No_connection_because_the_port_file_has_a_negative_port 390s === PAUSE TestServe/No_connection_because_the_port_file_has_a_negative_port 390s === RUN TestServe/No_connection_because_there_is_no_server 390s === PAUSE TestServe/No_connection_because_there_is_no_server 390s === RUN TestServe/No_connection_because_there_are_no_certificates 390s === PAUSE TestServe/No_connection_because_there_are_no_certificates 390s === RUN TestServe/Error_because_WindowsHostAddress_returns_an_error 390s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_an_error 390s === RUN TestServe/Success 390s === PAUSE TestServe/Success 390s === RUN TestServe/No_connection_because_the_port_file_does_not_exist 390s === PAUSE TestServe/No_connection_because_the_port_file_does_not_exist 390s === CONT TestNew/Error_when_WslPath_returns_error 390s time="2024-04-19T08:31:26Z" level=debug msg="Building new daemon" 392s === CONT TestReconnection/Success_connecting_after_failing_to_connect 392s time="2024-04-19T08:31:28Z" level=debug msg="Building new daemon" 394s time="2024-04-19T08:31:30Z" level=debug msg="Ready state sent to systemd" 394s time="2024-04-19T08:31:30Z" level=info msg="Daemon: connecting to Windows Agent" 394s time="2024-04-19T08:31:30Z" level=debug msg="Updated systemd status to \"Connecting\"" 394s time="2024-04-19T08:31:30Z" 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_connect2358499011/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect2358499011/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 394s time="2024-04-19T08:31:30Z" level=info msg="Reconnecting to Windows host in 1 seconds" 394s time="2024-04-19T08:31:30Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 394s time="2024-04-19T08:31:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42481" 395s time="2024-04-19T08:31:31Z" level=info msg="Daemon: connecting to Windows Agent" 395s time="2024-04-19T08:31:31Z" level=debug msg="Updated systemd status to \"Connecting\"" 396s time="2024-04-19T08:31:32Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42481" 396s time="2024-04-19T08:31:32Z" level=info msg="Daemon: completed connection to Windows Agent" 396s time="2024-04-19T08:31:32Z" level=debug msg="Updated systemd status to \"Connected\"" 396s time="2024-04-19T08:31:32Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 396s time="2024-04-19T08:31:32Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 397s time="2024-04-19T08:31:33Z" level=debug msg="Server: sent preface messages to all streams" 397s time="2024-04-19T08:31:33Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 397s time="2024-04-19T08:31:33Z" level=info msg="MockWindowsAgent: Connected ready" 397s time="2024-04-19T08:31:33Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 397s time="2024-04-19T08:31:33Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 397s time="2024-04-19T08:31:33Z" level=info msg="Stopping daemon requested." 397s time="2024-04-19T08:31:33Z" level=info msg="Stopping active requests." 397s time="2024-04-19T08:31:33Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 397s time="2024-04-19T08:31:33Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 397s time="2024-04-19T08:31:33Z" level=warning msg="Daemon: disconnected from Windows host" 397s time="2024-04-19T08:31:33Z" level=info msg="Reconnecting to Windows host in 2 seconds" 397s time="2024-04-19T08:31:33Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 397s time="2024-04-19T08:31:33Z" level=debug msg="Updated systemd status to \"Stopped\"" 397s time="2024-04-19T08:31:33Z" level=debug msg="All connections have now ended." 397s === CONT TestNew/Success 397s time="2024-04-19T08:31:33Z" level=debug msg="Building new daemon" 399s --- PASS: TestNew (0.00s) 399s --- PASS: TestNew/Error_when_WslPath_returns_error (2.07s) 399s --- PASS: TestNew/Success (2.04s) 399s === CONT TestServeAndQuit/Success_with_graceful_quit 399s time="2024-04-19T08:31:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:43331" 399s time="2024-04-19T08:31:35Z" level=debug msg="Building new daemon" 401s time="2024-04-19T08:31:37Z" level=debug msg="Ready state sent to systemd" 401s time="2024-04-19T08:31:37Z" level=info msg="Daemon: connecting to Windows Agent" 401s time="2024-04-19T08:31:37Z" level=debug msg="Updated systemd status to \"Connecting\"" 402s time="2024-04-19T08:31:38Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:43331" 402s time="2024-04-19T08:31:38Z" level=info msg="Daemon: completed connection to Windows Agent" 402s time="2024-04-19T08:31:38Z" level=debug msg="Updated systemd status to \"Connected\"" 402s time="2024-04-19T08:31:38Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 402s time="2024-04-19T08:31:38Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 403s time="2024-04-19T08:31:39Z" level=debug msg="Server: sent preface messages to all streams" 403s time="2024-04-19T08:31:39Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 403s time="2024-04-19T08:31:39Z" level=info msg="MockWindowsAgent: Connected ready" 403s time="2024-04-19T08:31:39Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 403s time="2024-04-19T08:31:39Z" level=info msg="Stopping daemon requested." 403s time="2024-04-19T08:31:39Z" level=info msg="Waiting for active requests to close." 403s time="2024-04-19T08:31:39Z" level=warning msg="Daemon: disconnected from Windows host" 403s time="2024-04-19T08:31:39Z" level=info msg="Reconnecting to Windows host in 1 seconds" 403s time="2024-04-19T08:31:39Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 403s time="2024-04-19T08:31:39Z" level=debug msg="Updated systemd status to \"Stopped\"" 403s time="2024-04-19T08:31:39Z" level=debug msg="All connections have now ended." 403s time="2024-04-19T08:31:39Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 403s time="2024-04-19T08:31:39Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 403s time="2024-04-19T08:31:39Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 403s === CONT TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped 403s time="2024-04-19T08:31:39Z" level=debug msg="Building new daemon" 405s time="2024-04-19T08:31:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35977" 405s time="2024-04-19T08:31:41Z" level=debug msg="Ready state sent to systemd" 405s time="2024-04-19T08:31:41Z" level=info msg="Daemon: connecting to Windows Agent" 405s time="2024-04-19T08:31:41Z" level=debug msg="Updated systemd status to \"Connecting\"" 406s time="2024-04-19T08:31:42Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:35977" 406s time="2024-04-19T08:31:42Z" level=info msg="Daemon: completed connection to Windows Agent" 406s time="2024-04-19T08:31:42Z" level=debug msg="Updated systemd status to \"Connected\"" 406s time="2024-04-19T08:31:42Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 406s time="2024-04-19T08:31:42Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 407s time="2024-04-19T08:31:43Z" level=debug msg="Server: sent preface messages to all streams" 407s time="2024-04-19T08:31:43Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 407s time="2024-04-19T08:31:43Z" level=info msg="MockWindowsAgent: Connected ready" 407s time="2024-04-19T08:31:43Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 472s time="2024-04-19T08:32:48Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 472s time="2024-04-19T08:32:48Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45113" 472s time="2024-04-19T08:32:48Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 472s time="2024-04-19T08:32:48Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 472s time="2024-04-19T08:32:48Z" level=warning msg="Daemon: disconnected from Windows host" 472s time="2024-04-19T08:32:48Z" level=info msg="Daemon: connecting to Windows Agent" 472s time="2024-04-19T08:32:48Z" level=debug msg="Updated systemd status to \"Connecting\"" 473s time="2024-04-19T08:32:49Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45113" 473s time="2024-04-19T08:32:49Z" level=info msg="Daemon: completed connection to Windows Agent" 473s time="2024-04-19T08:32:49Z" level=debug msg="Updated systemd status to \"Connected\"" 473s time="2024-04-19T08:32:49Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 473s time="2024-04-19T08:32:49Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 474s time="2024-04-19T08:32:50Z" level=debug msg="Server: sent preface messages to all streams" 474s time="2024-04-19T08:32:50Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 474s time="2024-04-19T08:32:50Z" level=info msg="MockWindowsAgent: Connected ready" 474s time="2024-04-19T08:32:50Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 474s time="2024-04-19T08:32:50Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 474s time="2024-04-19T08:32:50Z" level=info msg="Stopping daemon requested." 474s time="2024-04-19T08:32:50Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 474s time="2024-04-19T08:32:50Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 474s time="2024-04-19T08:32:50Z" level=info msg="Stopping active requests." 474s time="2024-04-19T08:32:50Z" level=warning msg="Daemon: disconnected from Windows host" 474s time="2024-04-19T08:32:50Z" level=info msg="Reconnecting to Windows host in 1 seconds" 474s time="2024-04-19T08:32:50Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 474s time="2024-04-19T08:32:50Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s time="2024-04-19T08:32:50Z" level=debug msg="All connections have now ended." 474s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 474s time="2024-04-19T08:32:50Z" level=debug msg="Building new daemon" 476s time="2024-04-19T08:32:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45261" 476s time="2024-04-19T08:32:52Z" level=debug msg="Ready state sent to systemd" 476s time="2024-04-19T08:32:52Z" level=info msg="Daemon: connecting to Windows Agent" 476s time="2024-04-19T08:32:52Z" level=debug msg="Updated systemd status to \"Connecting\"" 477s time="2024-04-19T08:32:53Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45261" 477s time="2024-04-19T08:32:53Z" level=info msg="Daemon: completed connection to Windows Agent" 477s time="2024-04-19T08:32:53Z" level=debug msg="Updated systemd status to \"Connected\"" 477s time="2024-04-19T08:32:53Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 477s time="2024-04-19T08:32:53Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 478s time="2024-04-19T08:32:54Z" level=debug msg="Server: sent preface messages to all streams" 478s time="2024-04-19T08:32:54Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 478s time="2024-04-19T08:32:54Z" level=info msg="MockWindowsAgent: Connected ready" 478s time="2024-04-19T08:32:54Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 478s time="2024-04-19T08:32:54Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 478s time="2024-04-19T08:32:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46261" 478s time="2024-04-19T08:32:54Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 478s time="2024-04-19T08:32:54Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 478s time="2024-04-19T08:32:54Z" level=warning msg="Daemon: disconnected from Windows host" 478s time="2024-04-19T08:32:54Z" level=info msg="Reconnecting to Windows host in 1 seconds" 478s time="2024-04-19T08:32:54Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 479s time="2024-04-19T08:32:55Z" level=info msg="Daemon: connecting to Windows Agent" 479s time="2024-04-19T08:32:55Z" level=debug msg="Updated systemd status to \"Connecting\"" 480s time="2024-04-19T08:32:56Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46261" 480s time="2024-04-19T08:32:56Z" level=info msg="Daemon: completed connection to Windows Agent" 480s time="2024-04-19T08:32:56Z" level=debug msg="Updated systemd status to \"Connected\"" 480s time="2024-04-19T08:32:56Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 480s time="2024-04-19T08:32:56Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 481s time="2024-04-19T08:32:57Z" level=debug msg="Server: sent preface messages to all streams" 481s time="2024-04-19T08:32:57Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 481s time="2024-04-19T08:32:57Z" level=info msg="MockWindowsAgent: Connected ready" 481s time="2024-04-19T08:32:57Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 481s time="2024-04-19T08:32:57Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 481s time="2024-04-19T08:32:57Z" level=info msg="Stopping daemon requested." 481s time="2024-04-19T08:32:57Z" level=info msg="Stopping active requests." 481s time="2024-04-19T08:32:57Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 481s time="2024-04-19T08:32:57Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 481s time="2024-04-19T08:32:57Z" level=warning msg="Daemon: disconnected from Windows host" 481s time="2024-04-19T08:32:57Z" level=info msg="Reconnecting to Windows host in 2 seconds" 481s time="2024-04-19T08:32:57Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 481s time="2024-04-19T08:32:57Z" level=debug msg="Updated systemd status to \"Stopped\"" 481s time="2024-04-19T08:32:57Z" level=debug msg="All connections have now ended." 481s --- PASS: TestReconnection (0.00s) 481s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (4.97s) 481s --- PASS: TestReconnection/Success_connecting_after_previous_long-lived_connection_dropped (71.06s) 481s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (6.96s) 481s === CONT TestServe/No_connection_because_the_port_file_has_a_bad_port 481s time="2024-04-19T08:32:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37561" 481s time="2024-04-19T08:32:57Z" level=debug msg="Building new daemon" 483s time="2024-04-19T08:32:59Z" level=info msg="Daemon: connecting to Windows Agent" 483s time="2024-04-19T08:32:59Z" 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" 483s time="2024-04-19T08:32:59Z" level=info msg="Reconnecting to Windows host in 1 seconds" 484s time="2024-04-19T08:33:00Z" level=info msg="Stopping daemon requested." 484s time="2024-04-19T08:33:00Z" level=info msg="Waiting for active requests to close." 484s time="2024-04-19T08:33:00Z" level=debug msg="All connections have now ended." 484s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 484s time="2024-04-19T08:33:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40813" 484s time="2024-04-19T08:33:00Z" level=debug msg="Building new daemon" 486s time="2024-04-19T08:33:02Z" level=info msg="Stopping daemon requested." 486s time="2024-04-19T08:33:02Z" level=info msg="Waiting for active requests to close." 486s time="2024-04-19T08:33:02Z" level=info msg="Stopping daemon requested." 486s time="2024-04-19T08:33:02Z" level=info msg="Waiting for active requests to close." 486s time="2024-04-19T08:33:02Z" level=debug msg="Updated systemd status to \"Stopped\"" 486s === CONT TestServeAndQuit/Success_with_double_quit 486s time="2024-04-19T08:33:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40827" 486s time="2024-04-19T08:33:02Z" level=debug msg="Building new daemon" 488s time="2024-04-19T08:33:04Z" level=debug msg="Ready state sent to systemd" 488s time="2024-04-19T08:33:04Z" level=info msg="Daemon: connecting to Windows Agent" 488s time="2024-04-19T08:33:04Z" level=debug msg="Updated systemd status to \"Connecting\"" 489s time="2024-04-19T08:33:05Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40827" 489s time="2024-04-19T08:33:05Z" level=info msg="Daemon: completed connection to Windows Agent" 489s time="2024-04-19T08:33:05Z" level=debug msg="Updated systemd status to \"Connected\"" 489s time="2024-04-19T08:33:05Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 489s time="2024-04-19T08:33:05Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 490s time="2024-04-19T08:33:06Z" level=debug msg="Server: sent preface messages to all streams" 490s time="2024-04-19T08:33:06Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 490s time="2024-04-19T08:33:06Z" level=info msg="MockWindowsAgent: Connected ready" 490s time="2024-04-19T08:33:06Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 490s time="2024-04-19T08:33:06Z" level=info msg="Stopping daemon requested." 490s time="2024-04-19T08:33:06Z" level=info msg="Waiting for active requests to close." 490s time="2024-04-19T08:33:06Z" level=warning msg="Daemon: disconnected from Windows host" 490s time="2024-04-19T08:33:06Z" level=info msg="Reconnecting to Windows host in 1 seconds" 490s time="2024-04-19T08:33:06Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 490s time="2024-04-19T08:33:06Z" level=debug msg="Updated systemd status to \"Stopped\"" 490s time="2024-04-19T08:33:06Z" level=debug msg="All connections have now ended." 490s time="2024-04-19T08:33:06Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 490s time="2024-04-19T08:33:06Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 490s time="2024-04-19T08:33:06Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 490s time="2024-04-19T08:33:06Z" level=info msg="Stopping daemon requested." 490s time="2024-04-19T08:33:06Z" level=info msg="Waiting for active requests to close." 490s time="2024-04-19T08:33:06Z" level=debug msg="All connections have now ended." 490s === CONT TestServeAndQuit/Success_with_forceful_quit 490s time="2024-04-19T08:33:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46461" 490s time="2024-04-19T08:33:06Z" level=debug msg="Building new daemon" 492s time="2024-04-19T08:33:08Z" level=debug msg="Ready state sent to systemd" 492s time="2024-04-19T08:33:08Z" level=info msg="Daemon: connecting to Windows Agent" 492s time="2024-04-19T08:33:08Z" level=debug msg="Updated systemd status to \"Connecting\"" 493s time="2024-04-19T08:33:09Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:46461" 493s time="2024-04-19T08:33:09Z" level=info msg="Daemon: completed connection to Windows Agent" 493s time="2024-04-19T08:33:09Z" level=debug msg="Updated systemd status to \"Connected\"" 493s time="2024-04-19T08:33:09Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 493s time="2024-04-19T08:33:09Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 494s time="2024-04-19T08:33:10Z" level=debug msg="Server: sent preface messages to all streams" 494s time="2024-04-19T08:33:10Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 494s time="2024-04-19T08:33:10Z" level=info msg="MockWindowsAgent: Connected ready" 494s time="2024-04-19T08:33:10Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 494s time="2024-04-19T08:33:10Z" level=info msg="Stopping daemon requested." 494s time="2024-04-19T08:33:10Z" level=info msg="Stopping active requests." 494s time="2024-04-19T08:33:10Z" level=warning msg="Daemon: disconnected from Windows host" 494s time="2024-04-19T08:33:10Z" level=info msg="Reconnecting to Windows host in 1 seconds" 494s time="2024-04-19T08:33:10Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 494s time="2024-04-19T08:33:10Z" level=debug msg="Updated systemd status to \"Stopped\"" 494s time="2024-04-19T08:33:10Z" level=debug msg="All connections have now ended." 494s time="2024-04-19T08:33:10Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 494s time="2024-04-19T08:33:10Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 494s time="2024-04-19T08:33:10Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 494s --- PASS: TestServeAndQuit (0.00s) 494s --- PASS: TestServeAndQuit/Success_with_graceful_quit (4.09s) 494s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (1.96s) 494s --- PASS: TestServeAndQuit/Success_with_double_quit (4.06s) 494s --- PASS: TestServeAndQuit/Success_with_forceful_quit (4.06s) 494s === CONT TestServe/No_connection_because_the_port_file_does_not_exist 494s time="2024-04-19T08:33:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46719" 494s time="2024-04-19T08:33:10Z" level=debug msg="Building new daemon" 496s time="2024-04-19T08:33:12Z" level=info msg="Daemon: connecting to Windows Agent" 496s time="2024-04-19T08:33:12Z" 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_exist2605270371/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_the_port_file_does_not_exist2605270371/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 496s time="2024-04-19T08:33:12Z" level=info msg="Reconnecting to Windows host in 1 seconds" 497s time="2024-04-19T08:33:13Z" level=info msg="Stopping daemon requested." 497s time="2024-04-19T08:33:13Z" level=info msg="Waiting for active requests to close." 497s time="2024-04-19T08:33:13Z" level=debug msg="All connections have now ended." 497s === CONT TestServe/Success 497s time="2024-04-19T08:33:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:45303" 497s time="2024-04-19T08:33:13Z" level=debug msg="Building new daemon" 499s time="2024-04-19T08:33:15Z" level=info msg="Daemon: connecting to Windows Agent" 500s time="2024-04-19T08:33:16Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:45303" 500s time="2024-04-19T08:33:16Z" level=info msg="Daemon: completed connection to Windows Agent" 500s time="2024-04-19T08:33:16Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 500s time="2024-04-19T08:33:16Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 501s time="2024-04-19T08:33:17Z" level=debug msg="Server: sent preface messages to all streams" 501s time="2024-04-19T08:33:17Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 501s time="2024-04-19T08:33:17Z" level=info msg="MockWindowsAgent: Connected ready" 501s time="2024-04-19T08:33:17Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 502s time="2024-04-19T08:33:18Z" level=info msg="Stopping daemon requested." 502s time="2024-04-19T08:33:18Z" level=info msg="Waiting for active requests to close." 502s time="2024-04-19T08:33:18Z" level=warning msg="Daemon: disconnected from Windows host" 502s time="2024-04-19T08:33:18Z" level=info msg="Reconnecting to Windows host in 1 seconds" 502s time="2024-04-19T08:33:18Z" level=debug msg="All connections have now ended." 502s time="2024-04-19T08:33:18Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 502s time="2024-04-19T08:33:18Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 502s time="2024-04-19T08:33:18Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 502s === CONT TestServe/Error_because_WindowsHostAddress_returns_an_error 502s time="2024-04-19T08:33:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:46063" 502s time="2024-04-19T08:33:18Z" level=debug msg="Building new daemon" 504s time="2024-04-19T08:33:20Z" level=info msg="Daemon: connecting to Windows Agent" 505s time="2024-04-19T08:33:21Z" level=info msg="Stopping daemon requested." 505s time="2024-04-19T08:33:21Z" level=info msg="Waiting for active requests to close." 505s time="2024-04-19T08:33:21Z" level=debug msg="All connections have now ended." 505s === CONT TestServe/No_connection_because_there_are_no_certificates 505s time="2024-04-19T08:33:21Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:42895" 505s time="2024-04-19T08:33:21Z" level=debug msg="Building new daemon" 507s time="2024-04-19T08:33:23Z" level=info msg="Daemon: connecting to Windows Agent" 508s time="2024-04-19T08:33:24Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:42895" 508s time="2024-04-19T08:33:24Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_there_are_no_certificates2075938644/001/mnt/d/Users/TestUser/.ubuntupro/certs/client_cert.pem: no such file or directory" 508s time="2024-04-19T08:33:24Z" level=info msg="Reconnecting to Windows host in 1 seconds" 508s time="2024-04-19T08:33:24Z" level=info msg="Stopping daemon requested." 508s time="2024-04-19T08:33:24Z" level=info msg="Waiting for active requests to close." 508s time="2024-04-19T08:33:24Z" level=debug msg="All connections have now ended." 508s === CONT TestServe/No_connection_because_there_is_no_server 508s time="2024-04-19T08:33:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:32939" 508s time="2024-04-19T08:33:24Z" level=debug msg="Building new daemon" 510s time="2024-04-19T08:33:26Z" level=info msg="Daemon: connecting to Windows Agent" 511s time="2024-04-19T08:33:27Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:32939" 511s time="2024-04-19T08:33:27Z" level=info msg="Daemon: completed connection to Windows Agent" 511s time="2024-04-19T08:33:27Z" 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:32939: connect: connection refused\"" 511s time="2024-04-19T08:33:27Z" level=info msg="Reconnecting to Windows host in 1 seconds" 511s time="2024-04-19T08:33:27Z" level=info msg="Stopping daemon requested." 511s time="2024-04-19T08:33:27Z" level=info msg="Waiting for active requests to close." 511s time="2024-04-19T08:33:27Z" level=debug msg="All connections have now ended." 511s === CONT TestServe/No_connection_because_the_port_file_has_a_negative_port 511s time="2024-04-19T08:33:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:38101" 511s time="2024-04-19T08:33:27Z" level=debug msg="Building new daemon" 513s time="2024-04-19T08:33:29Z" level=info msg="Daemon: connecting to Windows Agent" 513s time="2024-04-19T08:33:29Z" 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" 513s time="2024-04-19T08:33:29Z" level=info msg="Reconnecting to Windows host in 1 seconds" 514s time="2024-04-19T08:33:30Z" level=info msg="Daemon: connecting to Windows Agent" 514s time="2024-04-19T08:33:30Z" 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" 514s time="2024-04-19T08:33:30Z" level=info msg="Reconnecting to Windows host in 2 seconds" 514s time="2024-04-19T08:33:30Z" level=info msg="Stopping daemon requested." 514s time="2024-04-19T08:33:30Z" level=info msg="Waiting for active requests to close." 514s time="2024-04-19T08:33:30Z" level=debug msg="All connections have now ended." 514s === CONT TestServe/No_connection_because_the_port_file_is_empty 514s time="2024-04-19T08:33:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:41819" 514s time="2024-04-19T08:33:30Z" level=debug msg="Building new daemon" 516s time="2024-04-19T08:33:32Z" level=info msg="Daemon: connecting to Windows Agent" 516s time="2024-04-19T08:33:32Z" 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" 516s time="2024-04-19T08:33:32Z" level=info msg="Reconnecting to Windows host in 1 seconds" 517s time="2024-04-19T08:33:33Z" level=info msg="Daemon: connecting to Windows Agent" 517s time="2024-04-19T08:33:33Z" 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" 517s time="2024-04-19T08:33:33Z" level=info msg="Reconnecting to Windows host in 2 seconds" 517s time="2024-04-19T08:33:33Z" level=info msg="Stopping daemon requested." 517s time="2024-04-19T08:33:33Z" level=info msg="Waiting for active requests to close." 517s time="2024-04-19T08:33:33Z" level=debug msg="All connections have now ended." 517s === CONT TestServe/Success_with_systemd_notifier_returning_true 517s time="2024-04-19T08:33:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:37309" 517s time="2024-04-19T08:33:33Z" level=debug msg="Building new daemon" 519s time="2024-04-19T08:33:35Z" level=debug msg="Ready state sent to systemd" 519s time="2024-04-19T08:33:35Z" level=info msg="Daemon: connecting to Windows Agent" 519s time="2024-04-19T08:33:35Z" level=debug msg="Updated systemd status to \"Connecting\"" 520s time="2024-04-19T08:33:36Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:37309" 520s time="2024-04-19T08:33:36Z" level=info msg="Daemon: completed connection to Windows Agent" 520s time="2024-04-19T08:33:36Z" level=debug msg="Updated systemd status to \"Connected\"" 520s time="2024-04-19T08:33:36Z" level=debug msg="Started serving agentapi.ProAttachCmd requests" 520s time="2024-04-19T08:33:36Z" level=debug msg="Started serving agentapi.LandscapeConfigCmd requests" 521s time="2024-04-19T08:33:37Z" level=debug msg="Server: sent preface messages to all streams" 521s time="2024-04-19T08:33:37Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 521s time="2024-04-19T08:33:37Z" level=info msg="MockWindowsAgent: Connected ready" 521s time="2024-04-19T08:33:37Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 522s time="2024-04-19T08:33:38Z" level=info msg="Stopping daemon requested." 522s time="2024-04-19T08:33:38Z" level=info msg="Waiting for active requests to close." 522s time="2024-04-19T08:33:38Z" level=warning msg="Daemon: disconnected from Windows host" 522s time="2024-04-19T08:33:38Z" level=info msg="Reconnecting to Windows host in 1 seconds" 522s time="2024-04-19T08:33:38Z" level=debug msg="Updated systemd status to \"Not connected: waiting to retry\"" 522s time="2024-04-19T08:33:38Z" level=debug msg="Updated systemd status to \"Stopped\"" 522s time="2024-04-19T08:33:38Z" level=debug msg="All connections have now ended." 522s time="2024-04-19T08:33:38Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 522s === CONT TestServe/Error_because_the_notifier_returns_an_error 522s time="2024-04-19T08:33:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:44929" 522s time="2024-04-19T08:33:38Z" level=debug msg="Building new daemon" 522s time="2024-04-19T08:33:38Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 522s time="2024-04-19T08:33:38Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 524s time="2024-04-19T08:33:40Z" level=warning msg="Daemon: couldn't update systemd status to \"Stopped\": mock error" 524s time="2024-04-19T08:33:40Z" level=info msg="Stopping daemon requested." 524s time="2024-04-19T08:33:40Z" level=info msg="Waiting for active requests to close." 524s time="2024-04-19T08:33:40Z" level=debug msg="All connections have now ended." 524s === CONT TestServe/Error_because_the_context_is_pre-cancelled 524s time="2024-04-19T08:33:40Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40125" 524s time="2024-04-19T08:33:40Z" level=debug msg="Building new daemon" 526s time="2024-04-19T08:33:42Z" level=info msg="Stopping daemon requested." 526s time="2024-04-19T08:33:42Z" level=info msg="Waiting for active requests to close." 526s time="2024-04-19T08:33:42Z" level=debug msg="All connections have now ended." 526s === CONT TestServe/No_connection_because_cannot_read_root_CA_certificate_file 526s time="2024-04-19T08:33:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40741" 526s time="2024-04-19T08:33:42Z" level=debug msg="Building new daemon" 528s time="2024-04-19T08:33:44Z" level=info msg="Daemon: connecting to Windows Agent" 529s time="2024-04-19T08:33:45Z" level=info msg="Daemon: starting connection to Windows Agent via 127.0.0.1:40741" 529s time="2024-04-19T08:33:45Z" level=warning msg="Daemon: could not connect to Windows Agent: open /tmp/TestServeNo_connection_because_cannot_read_root_CA_certificate_file2692845723/001/mnt/d/Users/TestUser/.ubuntupro/certs/ca_cert.pem: no such file or directory" 529s time="2024-04-19T08:33:45Z" level=info msg="Reconnecting to Windows host in 1 seconds" 530s time="2024-04-19T08:33:46Z" level=info msg="Stopping daemon requested." 530s time="2024-04-19T08:33:46Z" level=info msg="Waiting for active requests to close." 530s time="2024-04-19T08:33:46Z" level=debug msg="All connections have now ended." 530s === CONT TestServe/No_connection_because_the_port_file_has_port_0 530s time="2024-04-19T08:33:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:35567" 530s time="2024-04-19T08:33:46Z" level=debug msg="Building new daemon" 532s time="2024-04-19T08:33:48Z" level=info msg="Daemon: connecting to Windows Agent" 532s time="2024-04-19T08:33:48Z" 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" 532s time="2024-04-19T08:33:48Z" level=info msg="Reconnecting to Windows host in 1 seconds" 533s time="2024-04-19T08:33:49Z" level=info msg="Stopping daemon requested." 533s time="2024-04-19T08:33:49Z" level=info msg="Waiting for active requests to close." 533s time="2024-04-19T08:33:49Z" level=debug msg="All connections have now ended." 533s --- PASS: TestServe (0.00s) 533s --- PASS: TestServe/No_connection_because_the_port_file_has_a_bad_port (3.01s) 533s --- PASS: TestServe/No_connection_because_the_port_file_does_not_exist (2.97s) 533s --- PASS: TestServe/Success (4.98s) 533s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_an_error (3.02s) 533s --- PASS: TestServe/No_connection_because_there_are_no_certificates (2.97s) 533s --- PASS: TestServe/No_connection_because_there_is_no_server (2.96s) 533s --- PASS: TestServe/No_connection_because_the_port_file_has_a_negative_port (2.96s) 533s --- PASS: TestServe/No_connection_because_the_port_file_is_empty (3.00s) 533s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (4.97s) 533s --- PASS: TestServe/Error_because_the_notifier_returns_an_error (2.04s) 533s --- PASS: TestServe/Error_because_the_context_is_pre-cancelled (2.15s) 533s --- PASS: TestServe/No_connection_because_cannot_read_root_CA_certificate_file (4.17s) 533s --- PASS: TestServe/No_connection_because_the_port_file_has_port_0 (3.19s) 533s PASS 533s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 143.678s 534s === RUN TestConnect 534s === PAUSE TestConnect 534s === RUN TestSendAndRecv 535s --- PASS: TestSendAndRecv (0.40s) 535s === RUN TestServe 535s === PAUSE TestServe 535s === RUN TestStop 535s === PAUSE TestStop 535s === RUN TestWithProMock 535s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 535s --- SKIP: TestWithProMock (0.00s) 535s === RUN TestWithWslPathMock 535s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 535s --- SKIP: TestWithWslPathMock (0.00s) 535s === RUN TestWithWslInfoMock 535s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 535s --- SKIP: TestWithWslInfoMock (0.00s) 535s === RUN TestWithCmdExeMock 535s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 535s --- SKIP: TestWithCmdExeMock (0.00s) 535s === CONT TestConnect 535s === RUN TestConnect/Success 535s === PAUSE TestConnect/Success 535s === RUN TestConnect/Error_dialing_an_address_that_is_not_serving 535s === PAUSE TestConnect/Error_dialing_an_address_that_is_not_serving 535s === CONT TestConnect/Success 535s === CONT TestStop 535s time="2024-04-19T08:33:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:33553" 536s time="2024-04-19T08:33:52Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 536s time="2024-04-19T08:33:52Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 536s time="2024-04-19T08:33:52Z" level=info msg="MockWindowsAgent: Connected ready" 546s time="2024-04-19T08:34:02Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 546s --- PASS: TestStop (11.32s) 546s === CONT TestServe 546s time="2024-04-19T08:34:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on 127.0.0.1:40515" 546s time="2024-04-19T08:34:02Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 546s time="2024-04-19T08:34:02Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 547s time="2024-04-19T08:34:03Z" level=info msg="MockWindowsAgent: LandscapeConfigCommands ready" 547s time="2024-04-19T08:34:03Z" level=info msg="MockWindowsAgent: ProAttachmentCommands ready" 547s time="2024-04-19T08:34:03Z" level=info msg="MockWindowsAgent: Connected ready" 551s --- PASS: TestServe (5.12s) 551s === CONT TestConnect/Error_dialing_an_address_that_is_not_serving 551s time="2024-04-19T08:34:07Z" level=warning msg="context.Background MockWindowsAgent: Connected stopped: rpc error: code = Canceled desc = context canceled" 551s time="2024-04-19T08:34:07Z" level=warning msg="context.Background MockWindowsAgent: ProAttachmentCommands stopped: rpc error: code = Canceled desc = context canceled" 551s time="2024-04-19T08:34:07Z" level=warning msg="context.Background MockWindowsAgent: LandscapeConfigCommands stopped: rpc error: code = Canceled desc = context canceled" 571s --- PASS: TestConnect (0.00s) 571s --- PASS: TestConnect/Success (0.30s) 571s --- PASS: TestConnect/Error_dialing_an_address_that_is_not_serving (20.01s) 571s PASS 571s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/streams 37.154s 573s === RUN TestInfo 573s === PAUSE TestInfo 573s === RUN TestWslDistroName 573s === PAUSE TestWslDistroName 573s === RUN TestUserProfileDir 573s === PAUSE TestUserProfileDir 573s === RUN TestProStatus 573s === PAUSE TestProStatus 573s === RUN TestProAttach 573s === PAUSE TestProAttach 573s === RUN TestProDetach 573s === PAUSE TestProDetach 573s === RUN TestLandscapeEnable 573s === PAUSE TestLandscapeEnable 573s === RUN TestWindowsHostAddress 573s === PAUSE TestWindowsHostAddress 573s === RUN TestLandscapeDisable 573s === PAUSE TestLandscapeDisable 573s === RUN TestRealBackend 573s === PAUSE TestRealBackend 573s === RUN TestWithProMock 573s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 573s --- SKIP: TestWithProMock (0.00s) 573s === RUN TestWithLandscapeConfigMock 573s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 573s --- SKIP: TestWithLandscapeConfigMock (0.00s) 573s === RUN TestWithWslPathMock 573s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 573s --- SKIP: TestWithWslPathMock (0.00s) 573s === RUN TestWithWslInfoMock 573s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 573s --- SKIP: TestWithWslInfoMock (0.00s) 573s === RUN TestWithCmdExeMock 573s mock_executables.go:629: Skipped because it is not a real test, but rather a mocked executable 573s --- SKIP: TestWithCmdExeMock (0.00s) 573s === CONT TestInfo 573s === RUN TestInfo/Success 573s === PAUSE TestInfo/Success 573s === RUN TestInfo/Error_when_WslDistroName_fails 573s === PAUSE TestInfo/Error_when_WslDistroName_fails 573s === RUN TestInfo/Error_when_pro_status_command_fails 573s === PAUSE TestInfo/Error_when_pro_status_command_fails 573s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 573s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 573s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 573s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 573s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 573s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 573s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 573s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 573s === CONT TestInfo/Success 574s === CONT TestRealBackend 574s --- PASS: TestRealBackend (0.00s) 574s === CONT TestLandscapeDisable 574s === RUN TestLandscapeDisable/Success 574s === PAUSE TestLandscapeDisable/Success 574s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 574s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 574s === CONT TestWindowsHostAddress 574s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 574s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 574s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 574s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 574s === RUN TestWindowsHostAddress/Success_without_NAT 574s === PAUSE TestWindowsHostAddress/Success_without_NAT 574s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 574s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 574s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 574s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 574s === CONT TestLandscapeEnable 574s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 574s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 574s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 574s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 574s === RUN TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 574s === PAUSE TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 574s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 574s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 574s === RUN TestLandscapeEnable/Success_overriding_computer_title 574s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 574s === RUN TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 574s === PAUSE TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 574s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 574s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 574s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 574s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 574s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 574s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 574s === RUN TestLandscapeEnable/Success 574s === PAUSE TestLandscapeEnable/Success 574s === CONT TestProDetach 574s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 574s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 574s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 574s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 574s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 574s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 574s === RUN TestProDetach/success_on_unattached_distro 574s === PAUSE TestProDetach/success_on_unattached_distro 574s === RUN TestProDetach/success_on_attached_distro 574s === PAUSE TestProDetach/success_on_attached_distro 574s === CONT TestProAttach 574s === RUN TestProAttach/success 574s === PAUSE TestProAttach/success 574s === RUN TestProAttach/error_on_'pro_attach'_error 574s === PAUSE TestProAttach/error_on_'pro_attach'_error 574s === CONT TestProStatus 574s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 574s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 574s === RUN TestProStatus/error_on_'pro_attach'_error 574s === PAUSE TestProStatus/error_on_'pro_attach'_error 574s === RUN TestProStatus/success_on_unattached_distro 574s === PAUSE TestProStatus/success_on_unattached_distro 574s === RUN TestProStatus/success_on_attached_distro 574s === PAUSE TestProStatus/success_on_attached_distro 574s === CONT TestUserProfileDir 574s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 574s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 574s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 574s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 574s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 574s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 574s === RUN TestUserProfileDir/Error_on_cmd.exe_error 574s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 574s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 574s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 574s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 574s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 574s === RUN TestUserProfileDir/Error_on_wslpath_error 574s === PAUSE TestUserProfileDir/Error_on_wslpath_error 574s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 574s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 574s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 574s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 574s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 574s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 574s === CONT TestWslDistroName 574s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 574s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 574s === RUN TestWslDistroName/Success_using_wslpath 574s === PAUSE TestWslDistroName/Success_using_wslpath 574s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 574s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 574s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 574s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 574s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 575s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 576s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 576s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 577s === CONT TestInfo/Error_when_pro_status_command_fails 578s === CONT TestInfo/Error_when_WslDistroName_fails 579s --- PASS: TestInfo (0.00s) 579s --- PASS: TestInfo/Success (1.04s) 579s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (0.95s) 579s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (0.93s) 579s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (0.93s) 579s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (0.93s) 579s --- PASS: TestInfo/Error_when_pro_status_command_fails (0.94s) 579s --- PASS: TestInfo/Error_when_WslDistroName_fails (0.96s) 579s === CONT TestLandscapeDisable/Success 580s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 581s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 582s --- PASS: TestLandscapeDisable (0.00s) 582s --- PASS: TestLandscapeDisable/Success (0.96s) 582s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (0.93s) 582s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 582s time="2024-04-19T08:34:38Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 583s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided_but_empty 583s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 584s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 585s === CONT TestWindowsHostAddress/Success_without_NAT 586s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 587s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 588s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 589s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 590s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 591s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 592s --- PASS: TestWindowsHostAddress (0.00s) 592s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (0.96s) 592s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (0.94s) 592s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (0.94s) 592s --- PASS: TestWindowsHostAddress/Success_without_NAT (0.95s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (0.93s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (0.95s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (0.94s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (0.93s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (0.97s) 592s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (0.93s) 592s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 593s === CONT TestLandscapeEnable/Success 593s system_test.go:456: testdata/TestLandscapeEnable/golden/success 593s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 594s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 594s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 594s === CONT TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided 594s time="2024-04-19T08:34:50Z" level=info msg="Landscape config contains key \"tags\". Its value will not be overridden." 595s system_test.go:456: testdata/TestLandscapeEnable/golden/do_not_append_wsl_tag_when_config_tag_is_provided 595s === CONT TestLandscapeEnable/Success_overriding_computer_title 595s time="2024-04-19T08:34:51Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden." 596s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 596s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 598s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 598s === CONT TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist 598s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 599s --- PASS: TestLandscapeEnable (0.00s) 599s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided_but_empty (0.94s) 599s --- PASS: TestLandscapeEnable/Success (0.93s) 599s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (0.95s) 599s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 599s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 599s --- PASS: TestLandscapeEnable/Do_not_append_wsl_tag_when_config_tag_is_provided (0.94s) 599s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (0.95s) 599s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (1.86s) 599s --- PASS: TestLandscapeEnable/Error_when_the_Landscape_user_does_not_exist (0.00s) 599s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (0.93s) 599s === CONT TestProAttach/success 600s === CONT TestProDetach/success_on_attached_distro 601s === CONT TestProDetach/success_on_unattached_distro 602s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 603s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 604s --- PASS: TestProDetach (0.00s) 604s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (0.94s) 604s --- PASS: TestProDetach/success_on_attached_distro (0.92s) 604s --- PASS: TestProDetach/success_on_unattached_distro (0.93s) 604s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (0.93s) 604s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (0.93s) 604s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 605s === CONT TestProAttach/error_on_'pro_attach'_error 606s --- PASS: TestProAttach (0.00s) 606s --- PASS: TestProAttach/success (0.99s) 606s --- PASS: TestProAttach/error_on_'pro_attach'_error (0.95s) 606s === CONT TestProStatus/success_on_attached_distro 607s === CONT TestProStatus/success_on_unattached_distro 608s === CONT TestProStatus/error_on_'pro_attach'_error 608s --- PASS: TestProStatus (0.00s) 608s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (0.93s) 608s --- PASS: TestProStatus/success_on_attached_distro (0.93s) 608s --- PASS: TestProStatus/success_on_unattached_distro (0.93s) 608s --- PASS: TestProStatus/error_on_'pro_attach'_error (0.93s) 608s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 610s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 610s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 612s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 614s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 616s === CONT TestUserProfileDir/Error_on_wslpath_error 618s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 618s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 618s system_test.go:205: Removing default proc/mounts 618s === CONT TestUserProfileDir/Error_on_cmd.exe_error 619s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 619s system_test.go:205: Removing default proc/mounts 619s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 621s --- PASS: TestUserProfileDir (0.00s) 621s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (1.90s) 621s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (1.87s) 621s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (1.86s) 621s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (1.88s) 621s --- PASS: TestUserProfileDir/Error_on_wslpath_error (1.86s) 621s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 621s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 621s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (0.98s) 621s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 621s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (1.92s) 621s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 622s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 623s === CONT TestWslDistroName/Success_using_wslpath 624s --- PASS: TestWslDistroName (0.00s) 624s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 624s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.01s) 624s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.01s) 624s --- PASS: TestWslDistroName/Success_using_wslpath (1.04s) 624s PASS 624s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 51.227s 624s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 624s autopkgtest [08:35:20]: test command1: -----------------------] 626s command1 PASS 626s autopkgtest [08:35:22]: test command1: - - - - - - - - - - results - - - - - - - - - - 626s autopkgtest [08:35:22]: @@@@@@@@@@@@@@@@@@@@ summary 626s command1 PASS 637s Creating nova instance adt-noble-ppc64el-wsl-pro-service-20240419-082455-juju-7f2275-prod-proposed-migration-environment-2-cec2ee3c-232b-4a8b-9202-da64705b4d81 from image adt/ubuntu-noble-ppc64el-server-20240418.img (UUID eb57e9e5-70fe-4878-ae85-dd88e864cb24)...